builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0167 starttime: 1467240901.37 results: success (0) buildid: 20160629150227 builduid: e7e8e743a1f84dd494aee6616e70cd3d revision: b6ad395da0e5c99a4f4b6a5554d7df03a047393b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:01.369966) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:01.370445) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:01.370749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:01.395705) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:01.396092) ========= 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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-06-29 15:55:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 187M=0s 2016-06-29 15:55:01 (187 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126772 ========= master_lag: 0.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:01.724454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:01.724937) ========= 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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.028611 ========= master_lag: 0.16 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:01.913161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 15:55:01.913749) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b6ad395da0e5c99a4f4b6a5554d7df03a047393b --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 b6ad395da0e5c99a4f4b6a5554d7df03a047393b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xDhTEjxZC7/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.VwXynJ4Wir/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-06-29 15:55:01,989 truncating revision to first 12 chars 2016-06-29 15:55:01,989 Setting DEBUG logging. 2016-06-29 15:55:01,989 attempt 1/10 2016-06-29 15:55:01,989 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b6ad395da0e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 15:55:02,729 unpacking tar archive at: fx-team-b6ad395da0e5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.994455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 15:55:02.931781) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:02.932349) ========= script_repo_revision: b6ad395da0e5c99a4f4b6a5554d7df03a047393b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:02.932792) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:02.933087) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 15:55:02.951355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-06-29 15:55:02.951774) ========= /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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:55:03 INFO - MultiFileLogger online at 20160629 15:55:03 in /builds/slave/test 15:55:03 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 15:55:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:55:03 INFO - {'append_to_log': False, 15:55:03 INFO - 'base_work_dir': '/builds/slave/test', 15:55:03 INFO - 'blob_upload_branch': 'Fx-Team', 15:55:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:55:03 INFO - 'branch': 'Fx-Team', 15:55:03 INFO - 'buildbot_json_path': 'buildprops.json', 15:55:03 INFO - 'config_files': ('talos/mac_config.py',), 15:55:03 INFO - 'default_actions': ('clobber', 15:55:03 INFO - 'read-buildbot-config', 15:55:03 INFO - 'download-and-extract', 15:55:03 INFO - 'populate-webroot', 15:55:03 INFO - 'create-virtualenv', 15:55:03 INFO - 'install', 15:55:03 INFO - 'run-tests'), 15:55:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:55:03 INFO - 'download_minidump_stackwalk': True, 15:55:03 INFO - 'download_symbols': 'ondemand', 15:55:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:55:03 INFO - 'tooltool.py': '/tools/tooltool.py', 15:55:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:55:03 INFO - '/tools/misc-python/virtualenv.py')}, 15:55:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:55:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:55:03 INFO - 'installer_path': 'installer.exe', 15:55:03 INFO - 'log_level': 'info', 15:55:03 INFO - 'log_name': 'talos', 15:55:03 INFO - 'log_to_console': True, 15:55:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:55:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:55:03 INFO - 'opt_config_files': (), 15:55:03 INFO - 'pip_index': False, 15:55:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:55:03 INFO - 'cmd': ('bash', 15:55:03 INFO - '-c', 15:55:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:55:03 INFO - 'enabled': True, 15:55:03 INFO - 'halt_on_failure': False, 15:55:03 INFO - 'name': 'check_screen_resolution'},), 15:55:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:55:03 INFO - 'cmd': ('bash', 15:55:03 INFO - '-c', 15:55:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:55:03 INFO - 'enabled': True, 15:55:03 INFO - 'halt_on_failure': False, 15:55:03 INFO - 'name': 'check_screen_resolution'},), 15:55:03 INFO - 'run_cmd_checks_enabled': True, 15:55:03 INFO - 'sps_profile': False, 15:55:03 INFO - 'sps_profile_interval': 0, 15:55:03 INFO - 'suite': 'dromaeojs', 15:55:03 INFO - 'system_bits': '32', 15:55:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:55:03 INFO - 'title': 't-yosemite-r7-0167', 15:55:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:55:03 INFO - 'use_talos_json': True, 15:55:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:55:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:55:03 INFO - 'work_dir': 'build'} 15:55:03 INFO - ##### 15:55:03 INFO - ##### Running clobber step. 15:55:03 INFO - ##### 15:55:03 INFO - Running pre-action listener: _resource_record_pre_action 15:55:03 INFO - Running main action method: clobber 15:55:03 INFO - rmtree: /builds/slave/test/build 15:55:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:55:05 INFO - Running post-action listener: _resource_record_post_action 15:55:05 INFO - ##### 15:55:05 INFO - ##### Finished clobber step (success) 15:55:05 INFO - ##### 15:55:05 INFO - ##### 15:55:05 INFO - ##### Running read-buildbot-config step. 15:55:05 INFO - ##### 15:55:05 INFO - Running pre-action listener: _resource_record_pre_action 15:55:05 INFO - Running main action method: read_buildbot_config 15:55:05 INFO - Using buildbot properties: 15:55:05 INFO - { 15:55:05 INFO - "project": "", 15:55:05 INFO - "product": "firefox", 15:55:05 INFO - "script_repo_revision": "production", 15:55:05 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:55:05 INFO - "repository": "", 15:55:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:55:05 INFO - "buildid": "20160629150227", 15:55:05 INFO - "pgo_build": "False", 15:55:05 INFO - "basedir": "/builds/slave/test", 15:55:05 INFO - "buildnumber": 71, 15:55:05 INFO - "slavename": "t-yosemite-r7-0167", 15:55:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:55:05 INFO - "platform": "yosemite_r7", 15:55:05 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:55:05 INFO - "branch": "fx-team", 15:55:05 INFO - "repo_path": "integration/fx-team", 15:55:05 INFO - "revision": "b6ad395da0e5c99a4f4b6a5554d7df03a047393b", 15:55:05 INFO - "stage_platform": "macosx64", 15:55:05 INFO - "builduid": "e7e8e743a1f84dd494aee6616e70cd3d", 15:55:05 INFO - "slavebuilddir": "test" 15:55:05 INFO - } 15:55:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 15:55:05 INFO - Running post-action listener: _resource_record_post_action 15:55:05 INFO - ##### 15:55:05 INFO - ##### Finished read-buildbot-config step (success) 15:55:05 INFO - ##### 15:55:05 INFO - ##### 15:55:05 INFO - ##### Running download-and-extract step. 15:55:05 INFO - ##### 15:55:05 INFO - Running pre-action listener: _resource_record_pre_action 15:55:05 INFO - Running main action method: download_and_extract 15:55:05 INFO - mkdir: /builds/slave/test/build/tests 15:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:05 INFO - https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:55:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 15:55:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 15:55:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:06 INFO - Downloaded 1199 bytes. 15:55:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:55:06 INFO - Using the following test package requirements: 15:55:06 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:55:06 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:55:06 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 15:55:06 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:55:06 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 15:55:06 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:55:06 INFO - u'jsshell-mac.zip'], 15:55:06 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:55:06 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 15:55:06 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:55:06 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:55:06 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 15:55:06 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:55:06 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 15:55:06 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:55:06 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 15:55:06 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:55:06 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 15:55:06 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:06 INFO - https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:55:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:55:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:55:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:15 INFO - Downloaded 18788457 bytes. 15:55:15 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'] 15:55:15 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 15:55:18 INFO - Return code: 0 15:55:18 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 15:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:18 INFO - https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:55:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:55:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:55:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:22 INFO - Downloaded 18788457 bytes. 15:55:22 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'] 15:55:22 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 15:55:26 INFO - Return code: 0 15:55:26 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')]} 15:55:26 INFO - https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:55:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:55:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:55:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:31 INFO - Downloaded 14405245 bytes. 15:55:31 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'] 15:55:31 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 15:55:32 INFO - Return code: 0 15:55:32 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')]} 15:55:32 INFO - https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:55:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:55:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:55:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 15:55:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uf0syrceTtGdhpJseBcdyA/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 15:55:36 INFO - Downloaded 112468300 bytes. 15:55:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:55:36 INFO - mkdir: /builds/slave/test/properties 15:55:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:55:36 INFO - Writing to file /builds/slave/test/properties/build_url 15:55:36 INFO - Contents: 15:55:36 INFO - build_url:https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:55:38 INFO - Running post-action listener: _resource_record_post_action 15:55:38 INFO - Running post-action listener: set_extra_try_arguments 15:55:38 INFO - ##### 15:55:38 INFO - ##### Finished download-and-extract step (success) 15:55:38 INFO - ##### 15:55:38 INFO - ##### 15:55:38 INFO - ##### Running populate-webroot step. 15:55:38 INFO - ##### 15:55:38 INFO - Running pre-action listener: _resource_record_pre_action 15:55:38 INFO - Running main action method: populate_webroot 15:55:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:55:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:55:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:55:38 INFO - '--tppagecycles', 15:55:38 INFO - '7'], 15:55:38 INFO - 'tests': ['tsvgm']}}, 15:55:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:55:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:55:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:55:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:55:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:38 INFO - 'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:38 INFO - 'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['damp', 'tps']}, 15:55:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:38 INFO - 'tests': ['damp', 'tps']}, 15:55:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['dromaeo_dom']}, 15:55:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:55:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['basic_compositor_video']}, 15:55:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:55:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['a11yr', 15:55:38 INFO - 'ts_paint', 15:55:38 INFO - 'tpaint', 15:55:38 INFO - 'sessionrestore', 15:55:38 INFO - 'sessionrestore_no_auto_restore', 15:55:38 INFO - 'tabpaint']}, 15:55:38 INFO - 'other-e10s': {'tests': ['a11yr', 15:55:38 INFO - 'ts_paint', 15:55:38 INFO - 'tpaint', 15:55:38 INFO - 'sessionrestore', 15:55:38 INFO - 'sessionrestore_no_auto_restore', 15:55:38 INFO - 'tabpaint']}, 15:55:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:55:38 INFO - 'ts_paint', 15:55:38 INFO - 'tpaint', 15:55:38 INFO - 'sessionrestore', 15:55:38 INFO - 'sessionrestore_no_auto_restore', 15:55:38 INFO - 'tabpaint']}, 15:55:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:55:38 INFO - 'ts_paint', 15:55:38 INFO - 'tpaint', 15:55:38 INFO - 'sessionrestore', 15:55:38 INFO - 'sessionrestore_no_auto_restore', 15:55:38 INFO - 'tabpaint']}, 15:55:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['a11yr', 15:55:38 INFO - 'ts_paint', 15:55:38 INFO - 'tpaint', 15:55:38 INFO - 'sessionrestore', 15:55:38 INFO - 'sessionrestore_no_auto_restore', 15:55:38 INFO - 'tabpaint']}, 15:55:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['a11yr', 15:55:38 INFO - 'ts_paint', 15:55:38 INFO - 'tpaint', 15:55:38 INFO - 'sessionrestore', 15:55:38 INFO - 'sessionrestore_no_auto_restore', 15:55:38 INFO - 'tabpaint']}, 15:55:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['tsvgx', 15:55:38 INFO - 'tsvgr_opacity', 15:55:38 INFO - 'tart', 15:55:38 INFO - 'tscrollx', 15:55:38 INFO - 'cart']}, 15:55:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:55:38 INFO - 'tsvgr_opacity', 15:55:38 INFO - 'tart', 15:55:38 INFO - 'tscrollx', 15:55:38 INFO - 'cart']}, 15:55:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:38 INFO - 'talos_options': ['--disable-e10s'], 15:55:38 INFO - 'tests': ['tp5o']}, 15:55:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:38 INFO - 'tests': ['tp5o']}, 15:55:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:38 INFO - 'talos_options': ['--disable-e10s', 15:55:38 INFO - '--xperf_path', 15:55:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:38 INFO - 'tests': ['tp5n']}, 15:55:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:38 INFO - 'talos_options': ['--xperf_path', 15:55:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:38 INFO - 'tests': ['tp5n']}}, 15:55:38 INFO - 'talos.zip': {'path': '', 15:55:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:55:38 INFO - Running post-action listener: _resource_record_post_action 15:55:38 INFO - ##### 15:55:38 INFO - ##### Finished populate-webroot step (success) 15:55:38 INFO - ##### 15:55:38 INFO - ##### 15:55:38 INFO - ##### Running create-virtualenv step. 15:55:38 INFO - ##### 15:55:38 INFO - Running pre-action listener: _resource_record_pre_action 15:55:38 INFO - Running main action method: create_virtualenv 15:55:38 INFO - Creating virtualenv /builds/slave/test/build/venv 15:55:38 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 15:55:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:55:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:55:38 INFO - Using real prefix '/tools/python27' 15:55:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:55:38 INFO - Installing distribute.............................................................................................................................................................................................done. 15:55:41 INFO - Installing pip.................done. 15:55:41 INFO - Return code: 0 15:55:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:55:41 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')]} 15:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:41 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')]} 15:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:41 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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:55:41 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 15:55:41 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 15:55:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:55:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:41 INFO - 'HOME': '/Users/cltbld', 15:55:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:41 INFO - 'LOGNAME': 'cltbld', 15:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:41 INFO - 'MOZ_NO_REMOTE': '1', 15:55:41 INFO - 'NO_EM_RESTART': '1', 15:55:41 INFO - 'PAGER': '/bin/cat', 15:55:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:41 INFO - 'PWD': '/builds/slave/test', 15:55:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:41 INFO - 'SHELL': '/bin/bash', 15:55:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:55:41 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:55:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:41 INFO - 'USER': 'cltbld', 15:55:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:41 INFO - 'XPC_FLAGS': '0x0', 15:55:41 INFO - 'XPC_SERVICE_NAME': '0', 15:55:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:42 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 15:55:42 INFO - Cleaning up... 15:55:42 INFO - Return code: 0 15:55:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:55: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')]} 15:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55: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')]} 15:55:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:55:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:55:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:55:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:42 INFO - 'HOME': '/Users/cltbld', 15:55:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:42 INFO - 'LOGNAME': 'cltbld', 15:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:42 INFO - 'MOZ_NO_REMOTE': '1', 15:55:42 INFO - 'NO_EM_RESTART': '1', 15:55:42 INFO - 'PAGER': '/bin/cat', 15:55:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:42 INFO - 'PWD': '/builds/slave/test', 15:55:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:42 INFO - 'SHELL': '/bin/bash', 15:55:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:55:42 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:55:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:42 INFO - 'USER': 'cltbld', 15:55:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:42 INFO - 'XPC_FLAGS': '0x0', 15:55:42 INFO - 'XPC_SERVICE_NAME': '0', 15:55:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:42 INFO - Downloading/unpacking psutil>=3.1.1 15:55:42 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 15:55:42 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 15:55:42 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 15:55:42 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 15:55:42 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 15:55:42 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 15:55:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:55:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:45 INFO - Installing collected packages: psutil 15:55:45 INFO - Running setup.py install for psutil 15:55:45 INFO - building 'psutil._psutil_osx' extension 15:55:45 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 15:55:45 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 15:55:45 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 15:55:46 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 15:55:46 INFO - building 'psutil._psutil_posix' extension 15:55:46 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 15:55:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:55:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:55:46 INFO - ^ 15:55:46 INFO - 1 warning generated. 15:55:46 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 15:55:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:46 INFO - Successfully installed psutil 15:55:46 INFO - Cleaning up... 15:55:46 INFO - Return code: 0 15:55:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:55:46 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')]} 15:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:46 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')]} 15:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:46 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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:55:46 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 15:55:46 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 15:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:46 INFO - 'HOME': '/Users/cltbld', 15:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:46 INFO - 'LOGNAME': 'cltbld', 15:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:46 INFO - 'MOZ_NO_REMOTE': '1', 15:55:46 INFO - 'NO_EM_RESTART': '1', 15:55:46 INFO - 'PAGER': '/bin/cat', 15:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:46 INFO - 'PWD': '/builds/slave/test', 15:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:46 INFO - 'SHELL': '/bin/bash', 15:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:55:46 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:46 INFO - 'USER': 'cltbld', 15:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:46 INFO - 'XPC_FLAGS': '0x0', 15:55:46 INFO - 'XPC_SERVICE_NAME': '0', 15:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:55:49 INFO - Installing collected packages: mozsystemmonitor 15:55:49 INFO - Running setup.py install for mozsystemmonitor 15:55:49 INFO - Successfully installed mozsystemmonitor 15:55:49 INFO - Cleaning up... 15:55:49 INFO - Return code: 0 15:55:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:55:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:55:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:55:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:55:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:49 INFO - 'HOME': '/Users/cltbld', 15:55:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:49 INFO - 'LOGNAME': 'cltbld', 15:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:49 INFO - 'MOZ_NO_REMOTE': '1', 15:55:49 INFO - 'NO_EM_RESTART': '1', 15:55:49 INFO - 'PAGER': '/bin/cat', 15:55:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:49 INFO - 'PWD': '/builds/slave/test', 15:55:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:49 INFO - 'SHELL': '/bin/bash', 15:55:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:55:49 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:55:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:49 INFO - 'USER': 'cltbld', 15:55:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:49 INFO - 'XPC_FLAGS': '0x0', 15:55:49 INFO - 'XPC_SERVICE_NAME': '0', 15:55:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:49 INFO - Downloading/unpacking jsonschema==2.5.1 15:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:55:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:52 INFO - Downloading functools32-3.2.3-2.tar.gz 15:55:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:55:52 INFO - warning: no files found matching '*.txt' 15:55:52 INFO - no previously-included directories found matching 'build' 15:55:52 INFO - no previously-included directories found matching 'dist' 15:55:52 INFO - no previously-included directories found matching '.git*' 15:55:52 INFO - Installing collected packages: jsonschema, functools32 15:55:52 INFO - Running setup.py install for functools32 15:55:52 INFO - warning: no files found matching '*.txt' 15:55:52 INFO - no previously-included directories found matching 'build' 15:55:52 INFO - no previously-included directories found matching 'dist' 15:55:52 INFO - no previously-included directories found matching '.git*' 15:55:52 INFO - Successfully installed jsonschema functools32 15:55:52 INFO - Cleaning up... 15:55:52 INFO - Return code: 0 15:55:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:52 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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:55:52 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 15:55:52 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 15:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:52 INFO - 'HOME': '/Users/cltbld', 15:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:52 INFO - 'LOGNAME': 'cltbld', 15:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:52 INFO - 'MOZ_NO_REMOTE': '1', 15:55:52 INFO - 'NO_EM_RESTART': '1', 15:55:52 INFO - 'PAGER': '/bin/cat', 15:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:52 INFO - 'PWD': '/builds/slave/test', 15:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:52 INFO - 'SHELL': '/bin/bash', 15:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:55:52 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:52 INFO - 'USER': 'cltbld', 15:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:52 INFO - 'XPC_FLAGS': '0x0', 15:55:52 INFO - 'XPC_SERVICE_NAME': '0', 15:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:53 INFO - Downloading/unpacking blobuploader==1.2.4 15:55:53 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 15:55:53 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 15:55:53 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 15:55:53 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 15:55:53 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 15:55:53 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 15:55:55 INFO - Downloading blobuploader-1.2.4.tar.gz 15:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:55:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:55: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 15:55: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 15:55: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 15:55: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 15:55: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 15:55: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 15:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:55:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:55:56 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 15:55:56 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 15:55:56 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 15:55:56 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 15:55:56 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 15:55:56 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 15:55:56 INFO - Downloading docopt-0.6.1.tar.gz 15:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:55:56 INFO - Installing collected packages: blobuploader, requests, docopt 15:55:56 INFO - Running setup.py install for blobuploader 15:55:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:55:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:55:56 INFO - Running setup.py install for requests 15:55:57 INFO - Running setup.py install for docopt 15:55:57 INFO - Successfully installed blobuploader requests docopt 15:55:57 INFO - Cleaning up... 15:55:57 INFO - Return code: 0 15:55:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:58 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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:55:58 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 15:55:58 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 15:55:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:55:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:58 INFO - 'HOME': '/Users/cltbld', 15:55:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:58 INFO - 'LOGNAME': 'cltbld', 15:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:58 INFO - 'MOZ_NO_REMOTE': '1', 15:55:58 INFO - 'NO_EM_RESTART': '1', 15:55:58 INFO - 'PAGER': '/bin/cat', 15:55:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:58 INFO - 'PWD': '/builds/slave/test', 15:55:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:58 INFO - 'SHELL': '/bin/bash', 15:55:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:55:58 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:55:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:58 INFO - 'USER': 'cltbld', 15:55:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:58 INFO - 'XPC_FLAGS': '0x0', 15:55:58 INFO - 'XPC_SERVICE_NAME': '0', 15:55:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_C5468-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-oYoDjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-NTXdwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-HTiSOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-RbU7Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-X19erB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-yeeW2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-zRzKbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-l1K6KY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-wTiA7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-KWKHGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-k86uXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-6pOZKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-vv7WKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-gPKxN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:59 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-jD4DjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:56:00 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-7tH900-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:56:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:56:00 INFO - Running setup.py install for manifestparser 15:56:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:56:00 INFO - Running setup.py install for mozcrash 15:56:00 INFO - Running setup.py install for mozdebug 15:56:00 INFO - Running setup.py install for mozdevice 15:56:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:56:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:56:00 INFO - Running setup.py install for mozfile 15:56:00 INFO - Running setup.py install for mozhttpd 15:56:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:56:01 INFO - Running setup.py install for mozinfo 15:56:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:56:01 INFO - Running setup.py install for mozInstall 15:56:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:56:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:56:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:56:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:56:01 INFO - Running setup.py install for mozleak 15:56:01 INFO - Running setup.py install for mozlog 15:56:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:56:01 INFO - Running setup.py install for moznetwork 15:56:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:56:01 INFO - Running setup.py install for mozprocess 15:56:01 INFO - Running setup.py install for mozprofile 15:56:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:56:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:56:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:56:02 INFO - Running setup.py install for mozrunner 15:56:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:56:02 INFO - Running setup.py install for mozscreenshot 15:56:02 INFO - Running setup.py install for moztest 15:56:02 INFO - Running setup.py install for mozversion 15:56:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:56:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:56:02 INFO - Cleaning up... 15:56:02 INFO - Return code: 0 15:56:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56:02 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')]} 15:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:02 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')]} 15:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:02 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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:56:02 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 15:56:02 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 15:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:02 INFO - 'HOME': '/Users/cltbld', 15:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:02 INFO - 'LOGNAME': 'cltbld', 15:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:02 INFO - 'MOZ_NO_REMOTE': '1', 15:56:02 INFO - 'NO_EM_RESTART': '1', 15:56:02 INFO - 'PAGER': '/bin/cat', 15:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:02 INFO - 'PWD': '/builds/slave/test', 15:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:02 INFO - 'SHELL': '/bin/bash', 15:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:56:02 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:02 INFO - 'USER': 'cltbld', 15:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:02 INFO - 'XPC_FLAGS': '0x0', 15:56:02 INFO - 'XPC_SERVICE_NAME': '0', 15:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-QLwTTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-jFIZXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-RegKIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-DfEp6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_779zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-XqX4QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-UTk0nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-9p5Rdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:56:03 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ruegr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:56:03 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)) 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:56:04 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-hrwPwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:56:04 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)) 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:56:04 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-HDKR69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:56:04 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)) 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:56:04 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-c6jJ8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:56:04 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-jk945U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:56:04 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)) 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:56:04 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-TYFIXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:04 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-RAP_wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:04 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)) 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:56:04 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-w9EvJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:56:04 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)) 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:56:04 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-htvn9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:56:04 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)) 15:56:04 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)) 15:56:04 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)) 15:56:04 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)) 15:56:04 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)) 15:56:04 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)) 15:56:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:08 INFO - Downloading blessings-1.6.tar.gz 15:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:56:08 INFO - Installing collected packages: blessings 15:56:08 INFO - Running setup.py install for blessings 15:56:08 INFO - Successfully installed blessings 15:56:08 INFO - Cleaning up... 15:56:08 INFO - Return code: 0 15:56:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:56:08 INFO - Reading from file tmpfile_stdout 15:56:08 INFO - Current package versions: 15:56:08 INFO - blessings == 1.6 15:56:08 INFO - blobuploader == 1.2.4 15:56:08 INFO - docopt == 0.6.1 15:56:08 INFO - functools32 == 3.2.3-2 15:56:08 INFO - jsonschema == 2.5.1 15:56:08 INFO - manifestparser == 1.1 15:56:08 INFO - mozInstall == 1.12 15:56:08 INFO - mozcrash == 0.17 15:56:08 INFO - mozdebug == 0.1 15:56:08 INFO - mozdevice == 0.48 15:56:08 INFO - mozfile == 1.2 15:56:08 INFO - mozhttpd == 0.7 15:56:08 INFO - mozinfo == 0.9 15:56:08 INFO - mozleak == 0.1 15:56:08 INFO - mozlog == 3.2 15:56:08 INFO - moznetwork == 0.27 15:56:08 INFO - mozprocess == 0.22 15:56:08 INFO - mozprofile == 0.28 15:56:08 INFO - mozrunner == 6.11 15:56:08 INFO - mozscreenshot == 0.1 15:56:08 INFO - mozsystemmonitor == 0.3 15:56:08 INFO - moztest == 0.7 15:56:08 INFO - mozversion == 1.4 15:56:08 INFO - psutil == 3.1.1 15:56:08 INFO - requests == 1.2.3 15:56:08 INFO - wsgiref == 0.1.2 15:56:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56: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')]} 15:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56: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')]} 15:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:08 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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:56:08 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 15:56:08 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 15:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:08 INFO - 'HOME': '/Users/cltbld', 15:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:08 INFO - 'LOGNAME': 'cltbld', 15:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:08 INFO - 'MOZ_NO_REMOTE': '1', 15:56:08 INFO - 'NO_EM_RESTART': '1', 15:56:08 INFO - 'PAGER': '/bin/cat', 15:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:08 INFO - 'PWD': '/builds/slave/test', 15:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:08 INFO - 'SHELL': '/bin/bash', 15:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:56:08 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:08 INFO - 'USER': 'cltbld', 15:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:08 INFO - 'XPC_FLAGS': '0x0', 15:56:08 INFO - 'XPC_SERVICE_NAME': '0', 15:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 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)) 15:56:08 INFO - Cleaning up... 15:56:08 INFO - Return code: 0 15:56:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:56: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')]} 15:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56: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')]} 15:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:56:08 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 0x10638ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106421618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce6b557c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, '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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 15:56:08 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 15:56:08 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 15:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:08 INFO - 'HOME': '/Users/cltbld', 15:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:08 INFO - 'LOGNAME': 'cltbld', 15:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:08 INFO - 'MOZ_NO_REMOTE': '1', 15:56:08 INFO - 'NO_EM_RESTART': '1', 15:56:08 INFO - 'PAGER': '/bin/cat', 15:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:08 INFO - 'PWD': '/builds/slave/test', 15:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:08 INFO - 'SHELL': '/bin/bash', 15:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:56:08 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:08 INFO - 'USER': 'cltbld', 15:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:08 INFO - 'XPC_FLAGS': '0x0', 15:56:08 INFO - 'XPC_SERVICE_NAME': '0', 15:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:56:09 INFO - Cleaning up... 15:56:09 INFO - Return code: 0 15:56:09 INFO - Running post-action listener: _resource_record_post_action 15:56:09 INFO - Running post-action listener: _start_resource_monitoring 15:56:09 INFO - Starting resource monitoring. 15:56:09 INFO - ##### 15:56:09 INFO - ##### Finished create-virtualenv step (success) 15:56:09 INFO - ##### 15:56:09 INFO - ##### 15:56:09 INFO - ##### Running install step. 15:56:09 INFO - ##### 15:56:09 INFO - Running pre-action listener: _resource_record_pre_action 15:56:09 INFO - Running main action method: install 15:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:56:09 INFO - Reading from file tmpfile_stdout 15:56:09 INFO - Detecting whether we're running mozinstall >=1.0... 15:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:56:09 INFO - Reading from file tmpfile_stdout 15:56:09 INFO - Output received: 15:56:09 INFO - Usage: mozinstall [options] installer 15:56:09 INFO - Options: 15:56:09 INFO - -h, --help show this help message and exit 15:56:09 INFO - -d DEST, --destination=DEST 15:56:09 INFO - Directory to install application into. [default: 15:56:09 INFO - "/builds/slave/test"] 15:56:09 INFO - --app=APP Application being installed. [default: firefox] 15:56:09 INFO - mkdir: /builds/slave/test/build/application 15:56:09 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'] 15:56:09 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 15:56:44 INFO - Reading from file tmpfile_stdout 15:56:44 INFO - Output received: 15:56:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:56:44 INFO - Running post-action listener: _resource_record_post_action 15:56:44 INFO - ##### 15:56:44 INFO - ##### Finished install step (success) 15:56:44 INFO - ##### 15:56:44 INFO - ##### 15:56:44 INFO - ##### Running run-tests step. 15:56:44 INFO - ##### 15:56:44 INFO - Running pre-action listener: _resource_record_pre_action 15:56:44 INFO - Running main action method: run_tests 15:56:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:56:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:56:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:56:44 INFO - 2016-06-29 15:56:44.412 screenresolution[1634:7460] starting screenresolution argv=screenresolution get 15:56:44 INFO - 2016-06-29 15:56:44.414 screenresolution[1634:7460] Display 0: 1600x1200x32@60 15:56:44 INFO - 2016-06-29 15:56:44.420 screenresolution[1635:7462] starting screenresolution argv=screenresolution list 15:56:44 INFO - Available Modes on Display 0 15:56:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:56:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:56:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:56:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:56:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:56:44 INFO - Intel Iris: 15:56:44 INFO - Chipset Model: Intel Iris 15:56:44 INFO - Type: GPU 15:56:44 INFO - Bus: Built-In 15:56:44 INFO - VRAM (Dynamic, Max): 1536 MB 15:56:44 INFO - Vendor: Intel (0x8086) 15:56:44 INFO - Device ID: 0x0a2e 15:56:44 INFO - Revision ID: 0x0009 15:56:44 INFO - Displays: 15:56:44 INFO - Display: 15:56:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:56:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:56:44 INFO - Display Serial Number: 1600x1200 60 15:56:44 INFO - Main Display: Yes 15:56:44 INFO - Mirror: Off 15:56:44 INFO - Online: Yes 15:56:44 INFO - Rotation: Supported 15:56:44 INFO - Return code: 0 15:56:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:56:44 INFO - Python 2.7.3 15:56:44 INFO - Return code: 0 15:56:44 INFO - grabbing minidump binary from tooltool 15:56:44 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')]} 15:56:44 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 0x1062f0d30>, '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 0x106055db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062e8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:56:44 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 15:56:44 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 15:56:44 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 15:56:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:56:44 INFO - Return code: 0 15:56:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:56:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:56:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:56:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:56:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:56:44 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/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 15:56:44 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/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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 15:56:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xDhTEjxZC7/Render', 15:56:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:44 INFO - 'HOME': '/Users/cltbld', 15:56:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:44 INFO - 'LOGNAME': 'cltbld', 15:56:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:44 INFO - 'MOZ_NO_REMOTE': '1', 15:56:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:44 INFO - 'NO_EM_RESTART': '1', 15:56:44 INFO - 'PAGER': '/bin/cat', 15:56:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:44 INFO - 'PWD': '/builds/slave/test', 15:56:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:44 INFO - 'SHELL': '/bin/bash', 15:56:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VwXynJ4Wir/Listeners', 15:56:44 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 15:56:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:44 INFO - 'USER': 'cltbld', 15:56:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:44 INFO - 'XPC_FLAGS': '0x0', 15:56:44 INFO - 'XPC_SERVICE_NAME': '0', 15:56:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:44 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/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 15:56:45 INFO - mozversion application_buildid: 20160629150227 15:56:45 INFO - mozversion application_changeset: b6ad395da0e5c99a4f4b6a5554d7df03a047393b 15:56:45 INFO - mozversion application_display_name: Nightly 15:56:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:56:45 INFO - mozversion application_name: Firefox 15:56:45 INFO - mozversion application_remotingname: firefox 15:56:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:56:45 INFO - mozversion application_vendor: Mozilla 15:56:45 INFO - mozversion application_version: 50.0a1 15:56:45 INFO - mozversion platform_buildid: 20160629150227 15:56:45 INFO - mozversion platform_changeset: b6ad395da0e5c99a4f4b6a5554d7df03a047393b 15:56:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:56:45 INFO - mozversion platform_version: 50.0a1 15:56:45 INFO - using testdate: 1467241005 15:56:45 INFO - actual date: 1467241005 15:56:45 INFO - starting webserver on 'localhost:49266' 15:56:45 INFO - SUITE-START | Running 2 tests 15:56:45 INFO - TEST-START | dromaeo_css 15:56:45 INFO - operating with platform_type : mac_ 15:56:45 INFO - Initialising browser for dromaeo_css test... 15:56:45 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmph5ws2W/profile http://localhost:49266/getInfo.html) 15:56:47 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 15:56:47 INFO - PROCESS | 1645 | colorDepth:24 15:56:47 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 15:56:47 INFO - PROCESS | 1645 | __metrics 15:56:47 INFO - PROCESS | 1645 | 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" 15:56:47 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:56:48 INFO - TEST-INFO | 1645: exit 0 15:56:48 INFO - Browser initialized. 15:56:48 INFO - Running cycle 1/1 for dromaeo_css test... 15:56:48 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmph5ws2W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:00 INFO - PROCESS | 1647 | RSS: Main: 243666944 15:57:00 INFO - PROCESS | 1647 | 15:57:52 INFO - PROCESS | 1647 | 1467241072108 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] 16:00:02 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 16:00:03 INFO - PROCESS | 1647 | RSS: Main: 335089664 16:00:03 INFO - PROCESS | 1647 | 16:03:07 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 16:03:07 INFO - PROCESS | 1647 | RSS: Main: 383025152 16:03:07 INFO - PROCESS | 1647 | 16:06:09 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 16:06:10 INFO - PROCESS | 1647 | RSS: Main: 371978240 16:06:10 INFO - PROCESS | 1647 | 16:09:12 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 16:09:13 INFO - PROCESS | 1647 | RSS: Main: 372310016 16:09:13 INFO - PROCESS | 1647 | 16:12:16 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 16:12:17 INFO - PROCESS | 1647 | RSS: Main: 345292800 16:12:17 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 16:15:20 INFO - PROCESS | 1647 | RSS: Main: 426692608 16:15:20 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | __start_tp_report 16:15:20 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 16:15:20 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 16:15:20 INFO - PROCESS | 1647 | |i|pagename|runs| 16:15:20 INFO - PROCESS | 1647 | |0;dojo.html;3386.2888793353395;3407.6081250656166;3408.9147771305697;3424.349373619013;3486.54674892264;4806.783694733736;5583.246261754662;5666.575006874491;5735.311762588485;5786.681732504705;5713.942860571393;5742.368339482654;5807.73865176067;5829.154772557977;5830;5124.256982737501;5141.845744627649;5194.766235519392;5211.348581427322;5338.439463856278;5320.361556613212;5338.386085600167;5349.7057661828585;5358.517733403993;5396.973015134919;5158.716270605134;5217.47825217479;5226.451222621608;5262.605304602151;5274.208868669692;5158.3552055993005;5165.22521621756;5168.22476628505;5269.262303277545;5275.261463395128;5109.463541276625;5131.102057139986;5184.9740751296;5192.584593232532;5243.79024839006;5666.858328541819;5737.856553586152;5746.281714785652;5770.682612456313;5816.214811000503;6156.415140561639;6220;6268.937310626927;6274.090256912722;6335.176427064498;6273.1531243282025;6280.811575652738;6331.8733625327695;6341.207349081365;6384;5687.1753595729215;5868.178455016301;5908.468237858613;5927.673977931169;5935.9406405935815;6198.0702894565275;6257.2491301044165;6282.151909492251;6300.086487459293;6308.558400911892;5146.845594632167;6225.844353891189;6232.719527621268;6235.968820155961;6237.282712488031;6160.291566469913;6256.655883926337;6317;6332.145160403379;6353.269374022047;6152.077188421773;6189.6286222826775;6207.34822843604;6223.96888015566;6295.401936815995;6326.841828954313;6328.240611126695;6343.397377249154;6351;6355.014972679242;4949.7030178189425;4965.4041515018425;5033.79864805411;5045.217991211368;5095.082885080721;4509.661775366861;4520.2315606347;4550.294704320836;4553.658475614342;4570.611498022638;5766.913496297559;5783.88432231353;5793.594448388572;5796.362400135981;5805.535557155417;6244.12582238487;6269.310435173802;6303;6351.17434733491;6375.23497180332;5792.565557583198;5814.856869658697;5822.883542329129;5835.620684655484;5867.970660146672;5357.249985002025;5440.047991601533;5454.91817622736;5460.754266058037;5468.152436372368;6239.937600623935;6269.279032911182;6284.277308109626;6325.399087086719;6353.14232578596;6333.145025421509;6336.239651241881;6353.047042943595;6361.713722882574;6374.29882712892;6237.033259844731;6249.218847644044;6297.433231009231;6310.2743184534365;6328.114064031131;6101.065651476929;6270.2475702915945;6273.341299163704;6287.339829317856;6292.182016337847;6327.462165715781;6330.620162790247;6333.809985700436;6343.953247714076;6363.90454143179;4861;5913.408659134052;6119.265688117454;6155.922713525205;6176.227971503562;5537.972310138423;6116.694165291628;6145.723442445012;6165.352637972948;6166.537509686702;5932.940670593239;5986.910196346971;5995.820125396245;6066.181065556093;6069.878602428015;5571.247881535941;5901.29184497863;6118.908216376847;6133.969330153321;6143.017117261216;5986.07215881539;5986.700664966821;6002.549808764273;6002.969985150046;6007.519398448201 16:15:20 INFO - PROCESS | 1647 | |1;ext.html;496.59775581778786;517.878298599829;537.7229080932784;542.2381553916746;562.1399259133525;30172.094837154895;40753.18493630126;41554.58445415545;41752;41787.373189402184;42587.36118958218;42910.785446072805;43000;43135;43246.56753432465;8665.30677545798;8845.867311990325;9589.99305072966;9731;9737.269704772136;8810.295176385906;8904.975927768292;8919.197272245498;11668.066554675606;11939.701507462296;11867.406629668481;11924.16530842837;11933.16467847251;11953.820692689616;11962.82055769164;11738.7652246955;11878;11883;11891.643250702493;11899.167058305966;8593.312534997185;11144.888551114465;11389.886101139049;11391.145664075228;11393.259438136578;40655;41060;41184.1763164735;41195.76412707623;41293.58706412927;3523.4186359250693;3544.6987006104255;4086.4483294755423;4088.1823635273063;4237.978810105929;31306.37387252242;32490.512642310383;32515.512267316008;32582.511262331085;32735.83632081824;3160.336329370812;3301.0756988043145;3308.106811160973;3327.93344133116;3340.9665903340897;516184.41907790594;518801;519778;520559.7944020549;527529.3623531896;61330;61478;61927.6903615479;62000.759984800054;62170.68914655398;64578.031329529156;65021;65397.67301163464;65475;65478.01782973259;16413.42553010639;16642.58393540171;16672.583185420463;16687.666246675;16695;11569.42152892352;12679.23924564529;12862.614121576367;12932.159409638343;12947.287899165449;14689.41242350294;15569.377224911103;17000.48998530046;17329.826701733073;17454.040027798594;6627.403533681895;6650.467962562983;6652.234992975773;6685.431738302299;6735.6295403753265;927.4667066436825;1004.7237009822358;1059.380262546419;1060.7984482948216;1097.7969075721016;29729.108126756233;29904.55143172854;30699.539506907415;30926.22684432907;32644.857429989836;1029.0841151375282;1033.328336581213;1061.177587369779;1077.3159044007111;1099.8240281554913;15449.565611806842;15482;15574.922125389527;15740.527784166494;16168.43410480651;659010;663195.6840215768;663220;664643;665107;58563.70718146382;58581.4141858576;58749.11876321858;58841;58949;1151.7581307925261;1247.79587697872;1372.1149858341114;1403.9087459315112;1432.0977783996016;30183.849080754455;30872.69127308698;31038.844805775825;31489.055328340186;31979.68020319767;50522.24216636827;51038;51454.97090058103;51479;51698.22452663288;451291;452280;454135;454634.72682636377;455973;23616.17343392973;26231;26299.4740105193;26586.335341616614;26600.60099098554;46847;46903.76548117374;47476.287855681505;47684.80787980328;47895.76052119717;39239.607603924735;39920.40119398153;40142.39786403148;40461.79769101136;40675.57635482744;38297.425538616386;39696.801515992236;40317.1936561273;41059.97350066153;41118.97202569959;37975.810120949216;38432.42351364676;38506.80746596361;39194.608053919095;39438.2112357757;29300.413991720474;34716.47925281173;34925.126871828405;35311.11722206965;35559.82220088883;25510;25838.224853254433;25995.870020649774;26039.088631897794;26159.607605885547 16:15:20 INFO - PROCESS | 1647 | |2;jquery.html;356.5792365009288;374.7451732821681;383.30430269061634;386.43001572680294;402.9939550906735;15699.764503532457;16116.355345786169;21065.894670526664;21145.048472818722;21232;20840.270590529333;20899;21239.787602123975;21282.680759788578;21309.041093150805;5529.0324193266215;5654.632448890819;5898.705064746767;5922.763089476416;5936.495397891184;4819.879503012417;5833.562482813784;5899.557533185029;5901.291844978608;5909.261342332208;5685.744141513642;5805.187273781674;5808.099744539582;5817.41825817417;5859.560532960024;5771.3074431068335;5805.564582656297;5829.183914252008;5831.300243970751;5838.649681019152;5122.564582010534;5437.211604317391;5437.945620543784;5490.039243132436;5496.4778346057465;12823.679408014781;13155.013373996893;16416.589585260346;23300;24975.375615609755;2035.3079952816113;2164.5238047629496;2283.394900351408;2306.1006207579057;2427.61814924062;9716.708498745049;10128.03783640553;10196;10906.345619262791;15474.845251547453;1787.9910600446915;1793.3095758133213;1837.338558119076;1865.4590168851016;1897.354899334233;123610;133848;134156;135233;135303.32348338293;11157.218994670295;11274.323540587427;11362.829557556643;11451.484683189277;11821.468033938494;14069.94475414348;14190.148591084568;14206.644833879236;14215;14222.217778022308;6689.698963546554;6919;8440.451370660887;8461.915380846112;8507.319414446829;8316.251537361666;8345.791355216168;8370.288525475402;8416.915830841735;8506.064332923374;4404.405405270248;4495.707778994355;4503.909921801545;4516.593506584358;4521.186186486399;1623.602217456707;1883.5761953560338;1920.7611090846326;1952.5509132899513;1964.4695932098252;913.5021413329752;941.868138460616;969.5879251318162;975.5512464266517;998.256299057207;20905.686414703792;21279.78720212809;21350.679739803916;21357.67963480549;21407.67888481674;935.6678379175431;938.2775263047415;962.5812771444444;992.9337533192529;999.6701088640731;10532.46759678867;10943.233973621771;11046.46226532891;11237.60668376603;11335.263207891461;597163;599596;605759;607745;608737.9563102245;72141;106313.46843265735;134797;137019;140681;969.5588507229194;991.548845275398;996.4818294486827;1008.445355054717;1013.1793247469574;17376;17779;18780.967046812555;19167.041647917828;19181.040947952824;55259.44740552704;55353.72323138519;55832.441675582726;56376;56548.15177772419;77299.61350193214;78545.4290914167;78755;81135.97160071046;81411.59294203491;6805.897911531232;7884.250996155356;7971.481853679492;7990.720324788605;8241.381896357678;10588.364698118137;12466.376681165724;12535;12696.555620553236;12769.297688626843;10745.409002504937;10787.73030674208;10923.235373523778;10947.83578246311;10966.287191332538;10801.729956751144;10988.175886808362;11082.833757493483;11154.163437742363;11235;10908.236423450282;11145;11154.721131971766;11180.10559155282;11365.261258018203;6278.6546739929745;7273.3817625500305;7327.74352897646;7331.340179383881;7371.447141464519;8005.9599702001115;9744.561494732896;10680.116368580284;10915.83626245591;10939 16:15:20 INFO - PROCESS | 1647 | |3;mootools.html;1357.9728405431886;1498.4680438444354;1538.346202863783;1713.2547341906272;1893.5739458621813;7645.885311720329;8445.451045682026;8645.913540864589;8668.176523230299;8719.4332368396;8393.53835539045;8610.827783444343;8630.654773809056;8638.17937295956;8644.481331120138;2076.49125964139;2157.1802714968367;2158.2769772126358;2329.871857047862;2358.5518751437257;2122.0981083039724;2133.722616059918;2314.3057082875152;2398.136670798511;2399.2562305685206;2342.1451170322794;2351.7295511016196;2353.376355265856;2353.682252895863;2354.776296251853;2284.817214622826;2321.1527792355837;2334.5214231082673;2335.065973610552;2336.684547586071;2103.2638576498257;2305.3660243433023;2374.7031621047367;2378.025009746013;2383.5947888858936;8564.957175214084;8622.870656940151;8631.525266110362;8634.309255259626;8635.136486351315;5155.74221288934;5295.68225906443;5312.6281160318795;5312.6281160318795;5319.946800531984;3465.5841299044023;3521.9647803521893;3585.354636165489;3649.9635003650155;3657.5062366580705;1027.291169093323;1049.5801679328254;1071.196602548089;1075.6074032977906;1076.0261962923566;11424.771504569862;11498.540058397653;11505.884941150565;11507;11537.250078744855;4482.932756008662;4653.418322709661;4766.737829419415;4774.522547745198;4778.354922085544;9757.316987810786;9793.265505087147;9797.36317139398;9804.509774511245;9849.950750246346;5308.203769434538;5319.973400132974;5321.414644389114;5324.334458192726;5328.973355133277;4400.801963911632;4750.95249047512;4893.559579637849;4900.068986892479;4900.313956046157;3045.8477076146105;3713;3824.961750382516;3826.0052386379743;3885.922281554353;1159.2812456277163;1163.697438665936;1165.242592315002;1173.7124404520775;1179.0390831472323;812.3460614205503;868.9956550217335;893.7765558610347;924.2375040591539;940.7036783413203;5063.8480845574695;5617.38208797032;5645.9717701412055;5904.5571582131515;6010.669413182317;895.1809094678297;928.8250363290443;944.3389627260807;947.5972711597682;971.223021582731;3843.7501562398356;3920.4511368408444;3953.9802300988313;4011.779352135602;4044.676425885922;10296.948515257525;10547.630832920811;10708.89291107079;10714.73213169677;10721.678349649523;10076.59693612262;10566.894331056745;10648;10662.893371066346;10673.83989240162;7920.168382319889;8031.558264295403;8206.384521160937;8223.588820558949;8226.341892648574;5261.36863576358;5293.835356221625;5546.694931778791;5580.972095139661;5730.713464326684;9580.281478888972;9871.605135794487;9918.05778451046;9997.450140242065;10013.148882345082;10103.191744660262;10209.642662506776;10215.642452514126;10217.693469195936;10227.437490937773;7073.080499535027;7156.749513766993;7164;7167.498275120692;7169.7849064528145;7404.814879628052;8112.472689275178;8397.95801020991;8418.915810841812;8516.659333626585;7387.335139837441;7516.173220945582;7524.548527088392;7637.121731001006;7642.961785191261;7326.560406375635;7334.596597187206;7388.741394051182;7424.517406368569;7476.813079673052;6546.738130474728;6720.899186512109;6728.192616886005;6738.966305168443;6756.45948324123;4299.548547402478;4722.504137065559;4779.378680771478;4898.853034408973;4958.42978057528;4302.268614335508;4986.850394488026;5019.52314530119;5051.989602079525;5146.716930568705 16:15:20 INFO - PROCESS | 1647 | |4;prototype.html;281.48628752526645;301.7962875059335;306.1626451654724;309.9178717639826;313.74116354007936;4156.771377574232;5237.3453318335205;5244;5267.604929630279;5365.678059316444;4196.971741923233;4342;4874.902501949959;5247.825065835172;5253.133233016548;3751.1934933989187;3907.355286377744;3952.762834229941;3984.366409771994;4001.899952501182;3469.7397695172835;3547.503349531055;3909.2181563687236;3978.6021397860127;3998.1808277233936;3695.519582454277;3936.940945885814;3965.5637879833193;3973.2649459849877;3991.8203680834285;3944.644981951624;3973.7615743055508;3977.34373828318;4010.4786377770847;4036.8385264589383;3158.815444208422;3231.5314279429585;3302.719268862126;3455.9827200863833;3496.3531746626954;4132.40080188371;4999.675021123615;5041.798328066873;5046;5047.520485553867;4533.818647254106;4535.365048893124;4552.270137347819;4570.200214962369;4574.245249533823;5038.949610503884;5316.3620365556;5438.265834112383;5457.454254574551;5468.44551041822;3723.702103831714;3745.3071181831447;3752.7185461090526;3756.267527832074;3763.5295588051495;4956.2653071425075;5350.4649535046965;5462.918056229159;5558.138488534243;5767.798127065575;3028.9545656815167;3712.5916149223563;3744.120131769032;3759.4360845872793;3783.356829339019;3489.900681285387;3798.924021519554;3820.3696390095324;3821.4840996465678;3823.655870971626;3771.73597848148;4363.280058790264;4487.730736155841;4500.437445319335;4516.841910533116;5493.258410114583;5497.972510137423;5519.503244707996;5520;5637.23897273871;1290.2774446310095;1306.4186437035619;1309.9738005239842;1313.0283590143226;1313.487739781486;761.8019314978035;781.0471225105363;799.7523862774091;818.6725309876098;823.3742355809628;4000;4198.929273035356;4286.057067445157;4326.459192600925;4381.496127945264;4211.578842115825;4414.514403415622;4604.539546045436;4737.8341758039;4870.464248932614;3517.1734642359024;3660.4143337065943;4242.66058715302;4260.786960651955;4267.231898258281;2454.96238122358;2708.6072519484565;2962.200205944383;2962.4371369439737;3014.80403773754;4651.511516314747;5383.730813459311;5547;5790.94209057904;5877.972099625622;4905.8037678492465;5529.640573362719;5816.621919575214;5819.796307129315;5852.414758524199;4904.386951631046;5168.9741551292;5823.359430462646;5838.365257727843;5872;4599.494055653809;4702.48272689997;5116;5337.439568845215;5627.493525582553;4792.7124372537755;4997.9500204997485;5390.703511306916;5614.63504872182;5621.578381621476;5269.314989051399;5522.315693713456;5689.800856969986;5842.159719688285;5856.531477481706;3694.8706795262037;3715.851365945332;3774.9245015098995;3779.5653499847867;3806.069326618433;5621.690807005654;5636.943630563642;5655.547556195576;5658.104534980663;5660.462256085665;4393.648508119279;4609.709281401213;5283.75049344658;5391.002664507078;5447.754851031792;4985.875353116201;5402.9459705404015;5404.891997140656;5425.430329815313;5425.593080518898;4847.394075740533;4853.845000749541;5004.324416203766;5081.907389911187;5359.464053594609;5006.723285562159;5427.430119837518;5441.591880608891;5453.182022696627;5483.396826349178;4503.680604488756;5303.840884773463;5336.252924590638;5354.171767186481;5356.437574054824 16:15:20 INFO - PROCESS | 1647 | |5;yui.html;636.605304711079;716.3302312337964;760.1258552664439;780.9297163255305;788.9723859664913;14827.925860370697;16036.679266414694;16038.037717736945;16049.037057776512;16128.596785080364;14012.509562165325;14360.569182924502;14815.55553333399;14860.851391486081;14872.702545949074;679.2290749998738;700.6811900585247;829.4276948905251;842.3093063687778;852.5353682243192;824.2458150792019;838.5723281126639;838.8867502887093;862.7584276402586;865.3336930563462;888.4092078767612;891.1623074310128;892.7411050795262;894.4275663575316;894.9105089491064;641.8202903187115;705.2806137739528;888.1828717579842;889.7686601483629;889.8665200219954;528.4081828352232;534.8395481355577;535.2319421629935;635.9014352775328;660.5112216959416;14979.475718349806;15075.321610527442;15096.396144154221;15105;15117;13670.043096983216;13875.37560809766;13968.301584920713;14047.297635118202;14292.142471451642;11685.474153663106;12583.937080314632;12716.554920577735;12843.03677224212;13262.071654984155;4486.371907932892;4524.185646583614;4629.768511574442;4705.929411058837;4738.668293219476;110746;113242;114889.42555287253;115772.99999999916;116310;10949.069329106957;10960.440760411075;11132.443377831076;11235.66293011211;11243.887561124448;21913.123475061137;21914.013869375598;21922.561548768936;21933.451663708536;21949.231776888053;7811.960940195377;7821.687132514636;7827.099883513355;7893.407994400443;7967.920320796834;4061.4923134608175;4092.1815636872743;4123.299039163349;4132.793360331972;4134.028503301782;3033.2871775132826;3444.810535420576;3537.239493508908;3552.0054384772307;3560.2345495718027;627.5387590121258;642.0593830039015;649.5388274325283;656.9934300656932;666.1772710702279;546.126198083064;549.7690969792696;565.7850016993599;570.3355590736828;587.6620942957817;8404.663813447394;8528.445651032787;8644.394892357475;8747.693830715894;8785.69250076257;470.7618962129577;518.8806574487888;531.1581143886905;547.8904219156105;562.8452175651647;4175;4204.768737719455;4205.2640787862;4281.293586558183;4368.956310436855;72378;116192.83807161989;117292;121823;123074;89228.5538572303;99289.07820139779;116815.41592291984;123702.76297237093;124051.37974310252;84130.15869841223;94645;112569;113794.43102784599;114012.42993784978;7757;8154.714584989497;8187.4678145920325;8230.794230144294;8349.290310323691;46146.61560153201;51139.74430127825;55245.171322431;61971;67209.66395167993;89203.55398222967;90270;91263.63104553304;91710.541447295;92816;8999.325050621099;11216.887831121585;11412.201145919706;11414.48634811419;11515.769684606428;12230.327331996825;12245.938770306091;12423.192492487959;12447.128700991207;12936.477386909462;11649.009834164191;12007.699807504881;12019.158658893793;12061.819072714092;12411.441485133371;11497.82753258685;13200.669983250496;13517.797233041709;13579.388927498088;14442;13246.536371226961;13757.724845502833;13803.102798318077;13978.231197284247;14126;10959.123270138529;11124.429167203343;11165.441727913734;11558.942205289257;11812.586559470377;9544.618215271312;9895.554700038372;10186.868536345519;10202.863368188402;10314.793704126025 16:15:20 INFO - PROCESS | 1647 | __end_tp_report 16:15:20 INFO - PROCESS | 1647 | __start_cc_report 16:15:20 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1299 16:15:20 INFO - PROCESS | 1647 | __end_cc_report 16:15:20 INFO - PROCESS | 1647 | __startTimestamp1467242120725__endTimestamp 16:15:20 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | ------- Summary: start ------- 16:15:20 INFO - PROCESS | 1647 | Number of tests: 6 16:15:20 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5772.21 Median:5986.07 stddev:642.79 (10.7%) stddev-sans-first:616.91 16:15:20 INFO - PROCESS | 1647 | Values: 3386.3 3407.6 3408.9 3424.3 3486.5 4806.8 5583.2 5666.6 5735.3 5786.7 5713.9 5742.4 5807.7 5829.2 5830.0 5124.3 5141.8 5194.8 5211.3 5338.4 5320.4 5338.4 5349.7 5358.5 5397.0 5158.7 5217.5 5226.5 5262.6 5274.2 5158.4 5165.2 5168.2 5269.3 5275.3 5109.5 5131.1 5185.0 5192.6 5243.8 5666.9 5737.9 5746.3 5770.7 5816.2 6156.4 6220.0 6268.9 6274.1 6335.2 6273.2 6280.8 6331.9 6341.2 6384.0 5687.2 5868.2 5908.5 5927.7 5935.9 6198.1 6257.2 6282.2 6300.1 6308.6 5146.8 6225.8 6232.7 6236.0 6237.3 6160.3 6256.7 6317.0 6332.1 6353.3 6152.1 6189.6 6207.3 6224.0 6295.4 6326.8 6328.2 6343.4 6351.0 6355.0 4949.7 4965.4 5033.8 5045.2 5095.1 4509.7 4520.2 4550.3 4553.7 4570.6 5766.9 5783.9 5793.6 5796.4 5805.5 6244.1 6269.3 6303.0 6351.2 6375.2 5792.6 5814.9 5822.9 5835.6 5868.0 5357.2 5440.0 5454.9 5460.8 5468.2 6239.9 6269.3 6284.3 6325.4 6353.1 6333.1 6336.2 6353.0 6361.7 6374.3 6237.0 6249.2 6297.4 6310.3 6328.1 6101.1 6270.2 6273.3 6287.3 6292.2 6327.5 6330.6 6333.8 6344.0 6363.9 4861.0 5913.4 6119.3 6155.9 6176.2 5538.0 6116.7 6145.7 6165.4 6166.5 5932.9 5986.9 5995.8 6066.2 6069.9 5571.2 5901.3 6118.9 6134.0 6143.0 5986.1 5986.7 6002.5 6003.0 6007.5 16:15:20 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:68798.00 Median:38469.62 stddev:147542.18 (383.5%) stddev-sans-first:147866.96 16:15:20 INFO - PROCESS | 1647 | Values: 496.6 517.9 537.7 542.2 562.1 30172.1 40753.2 41554.6 41752.0 41787.4 42587.4 42910.8 43000.0 43135.0 43246.6 8665.3 8845.9 9590.0 9731.0 9737.3 8810.3 8905.0 8919.2 11668.1 11939.7 11867.4 11924.2 11933.2 11953.8 11962.8 11738.8 11878.0 11883.0 11891.6 11899.2 8593.3 11144.9 11389.9 11391.1 11393.3 40655.0 41060.0 41184.2 41195.8 41293.6 3523.4 3544.7 4086.4 4088.2 4238.0 31306.4 32490.5 32515.5 32582.5 32735.8 3160.3 3301.1 3308.1 3327.9 3341.0 516184.4 518801.0 519778.0 520559.8 527529.4 61330.0 61478.0 61927.7 62000.8 62170.7 64578.0 65021.0 65397.7 65475.0 65478.0 16413.4 16642.6 16672.6 16687.7 16695.0 11569.4 12679.2 12862.6 12932.2 12947.3 14689.4 15569.4 17000.5 17329.8 17454.0 6627.4 6650.5 6652.2 6685.4 6735.6 927.5 1004.7 1059.4 1060.8 1097.8 29729.1 29904.6 30699.5 30926.2 32644.9 1029.1 1033.3 1061.2 1077.3 1099.8 15449.6 15482.0 15574.9 15740.5 16168.4 659010.0 663195.7 663220.0 664643.0 665107.0 58563.7 58581.4 58749.1 58841.0 58949.0 1151.8 1247.8 1372.1 1403.9 1432.1 30183.8 30872.7 31038.8 31489.1 31979.7 50522.2 51038.0 51455.0 51479.0 51698.2 451291.0 452280.0 454135.0 454634.7 455973.0 23616.2 26231.0 26299.5 26586.3 26600.6 46847.0 46903.8 47476.3 47684.8 47895.8 39239.6 39920.4 40142.4 40461.8 40675.6 38297.4 39696.8 40317.2 41060.0 41119.0 37975.8 38432.4 38506.8 39194.6 39438.2 29300.4 34716.5 34925.1 35311.1 35559.8 25510.0 25838.2 25995.9 26039.1 26159.6 16:15:20 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35118.94 Median:12863.81 stddev:101033.97 (785.4%) stddev-sans-first:101283.68 16:15:20 INFO - PROCESS | 1647 | Values: 356.6 374.7 383.3 386.4 403.0 15699.8 16116.4 21065.9 21145.0 21232.0 20840.3 20899.0 21239.8 21282.7 21309.0 5529.0 5654.6 5898.7 5922.8 5936.5 4819.9 5833.6 5899.6 5901.3 5909.3 5685.7 5805.2 5808.1 5817.4 5859.6 5771.3 5805.6 5829.2 5831.3 5838.6 5122.6 5437.2 5437.9 5490.0 5496.5 12823.7 13155.0 16416.6 23300.0 24975.4 2035.3 2164.5 2283.4 2306.1 2427.6 9716.7 10128.0 10196.0 10906.3 15474.8 1788.0 1793.3 1837.3 1865.5 1897.4 123610.0 133848.0 134156.0 135233.0 135303.3 11157.2 11274.3 11362.8 11451.5 11821.5 14069.9 14190.1 14206.6 14215.0 14222.2 6689.7 6919.0 8440.5 8461.9 8507.3 8316.3 8345.8 8370.3 8416.9 8506.1 4404.4 4495.7 4503.9 4516.6 4521.2 1623.6 1883.6 1920.8 1952.6 1964.5 913.5 941.9 969.6 975.6 998.3 20905.7 21279.8 21350.7 21357.7 21407.7 935.7 938.3 962.6 992.9 999.7 10532.5 10943.2 11046.5 11237.6 11335.3 597163.0 599596.0 605759.0 607745.0 608738.0 72141.0 106313.5 134797.0 137019.0 140681.0 969.6 991.5 996.5 1008.4 1013.2 17376.0 17779.0 18781.0 19167.0 19181.0 55259.4 55353.7 55832.4 56376.0 56548.2 77299.6 78545.4 78755.0 81136.0 81411.6 6805.9 7884.3 7971.5 7990.7 8241.4 10588.4 12466.4 12535.0 12696.6 12769.3 10745.4 10787.7 10923.2 10947.8 10966.3 10801.7 10988.2 11082.8 11154.2 11235.0 10908.2 11145.0 11154.7 11180.1 11365.3 6278.7 7273.4 7327.7 7331.3 7371.4 8006.0 9744.6 10680.1 10915.8 10939.0 16:15:20 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5620.14 Median:4929.37 stddev:3170.67 (64.3%) stddev-sans-first:3163.39 16:15:20 INFO - PROCESS | 1647 | Values: 1358.0 1498.5 1538.3 1713.3 1893.6 7645.9 8445.5 8645.9 8668.2 8719.4 8393.5 8610.8 8630.7 8638.2 8644.5 2076.5 2157.2 2158.3 2329.9 2358.6 2122.1 2133.7 2314.3 2398.1 2399.3 2342.1 2351.7 2353.4 2353.7 2354.8 2284.8 2321.2 2334.5 2335.1 2336.7 2103.3 2305.4 2374.7 2378.0 2383.6 8565.0 8622.9 8631.5 8634.3 8635.1 5155.7 5295.7 5312.6 5312.6 5319.9 3465.6 3522.0 3585.4 3650.0 3657.5 1027.3 1049.6 1071.2 1075.6 1076.0 11424.8 11498.5 11505.9 11507.0 11537.3 4482.9 4653.4 4766.7 4774.5 4778.4 9757.3 9793.3 9797.4 9804.5 9850.0 5308.2 5320.0 5321.4 5324.3 5329.0 4400.8 4751.0 4893.6 4900.1 4900.3 3045.8 3713.0 3825.0 3826.0 3885.9 1159.3 1163.7 1165.2 1173.7 1179.0 812.3 869.0 893.8 924.2 940.7 5063.8 5617.4 5646.0 5904.6 6010.7 895.2 928.8 944.3 947.6 971.2 3843.8 3920.5 3954.0 4011.8 4044.7 10296.9 10547.6 10708.9 10714.7 10721.7 10076.6 10566.9 10648.0 10662.9 10673.8 7920.2 8031.6 8206.4 8223.6 8226.3 5261.4 5293.8 5546.7 5581.0 5730.7 9580.3 9871.6 9918.1 9997.5 10013.1 10103.2 10209.6 10215.6 10217.7 10227.4 7073.1 7156.7 7164.0 7167.5 7169.8 7404.8 8112.5 8398.0 8418.9 8516.7 7387.3 7516.2 7524.5 7637.1 7643.0 7326.6 7334.6 7388.7 7424.5 7476.8 6546.7 6720.9 6728.2 6739.0 6756.5 4299.5 4722.5 4779.4 4898.9 4958.4 4302.3 4986.9 5019.5 5052.0 5146.7 16:15:20 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4327.69 Median:4630.61 stddev:1332.13 (28.8%) stddev-sans-first:1300.79 16:15:20 INFO - PROCESS | 1647 | Values: 281.5 301.8 306.2 309.9 313.7 4156.8 5237.3 5244.0 5267.6 5365.7 4197.0 4342.0 4874.9 5247.8 5253.1 3751.2 3907.4 3952.8 3984.4 4001.9 3469.7 3547.5 3909.2 3978.6 3998.2 3695.5 3936.9 3965.6 3973.3 3991.8 3944.6 3973.8 3977.3 4010.5 4036.8 3158.8 3231.5 3302.7 3456.0 3496.4 4132.4 4999.7 5041.8 5046.0 5047.5 4533.8 4535.4 4552.3 4570.2 4574.2 5038.9 5316.4 5438.3 5457.5 5468.4 3723.7 3745.3 3752.7 3756.3 3763.5 4956.3 5350.5 5462.9 5558.1 5767.8 3029.0 3712.6 3744.1 3759.4 3783.4 3489.9 3798.9 3820.4 3821.5 3823.7 3771.7 4363.3 4487.7 4500.4 4516.8 5493.3 5498.0 5519.5 5520.0 5637.2 1290.3 1306.4 1310.0 1313.0 1313.5 761.8 781.0 799.8 818.7 823.4 4000.0 4198.9 4286.1 4326.5 4381.5 4211.6 4414.5 4604.5 4737.8 4870.5 3517.2 3660.4 4242.7 4260.8 4267.2 2455.0 2708.6 2962.2 2962.4 3014.8 4651.5 5383.7 5547.0 5790.9 5878.0 4905.8 5529.6 5816.6 5819.8 5852.4 4904.4 5169.0 5823.4 5838.4 5872.0 4599.5 4702.5 5116.0 5337.4 5627.5 4792.7 4998.0 5390.7 5614.6 5621.6 5269.3 5522.3 5689.8 5842.2 5856.5 3694.9 3715.9 3774.9 3779.6 3806.1 5621.7 5636.9 5655.5 5658.1 5660.5 4393.6 4609.7 5283.8 5391.0 5447.8 4985.9 5402.9 5404.9 5425.4 5425.6 4847.4 4853.8 5004.3 5081.9 5359.5 5006.7 5427.4 5441.6 5453.2 5483.4 4503.7 5303.8 5336.3 5354.2 5356.4 16:15:20 INFO - PROCESS | 1647 | 16:15:20 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:22846.11 Median:4133.41 stddev:35286.61 (853.7%) stddev-sans-first:35346.20 16:15:20 INFO - PROCESS | 1647 | Values: 636.6 716.3 760.1 780.9 789.0 14827.9 16036.7 16038.0 16049.0 16128.6 14012.5 14360.6 14815.6 14860.9 14872.7 679.2 700.7 829.4 842.3 852.5 824.2 838.6 838.9 862.8 865.3 888.4 891.2 892.7 894.4 894.9 641.8 705.3 888.2 889.8 889.9 528.4 534.8 535.2 635.9 660.5 14979.5 15075.3 15096.4 15105.0 15117.0 13670.0 13875.4 13968.3 14047.3 14292.1 11685.5 12583.9 12716.6 12843.0 13262.1 4486.4 4524.2 4629.8 4705.9 4738.7 110746.0 113242.0 114889.4 115773.0 116310.0 10949.1 10960.4 11132.4 11235.7 11243.9 21913.1 21914.0 21922.6 21933.5 21949.2 7812.0 7821.7 7827.1 7893.4 7967.9 4061.5 4092.2 4123.3 4132.8 4134.0 3033.3 3444.8 3537.2 3552.0 3560.2 627.5 642.1 649.5 657.0 666.2 546.1 549.8 565.8 570.3 587.7 8404.7 8528.4 8644.4 8747.7 8785.7 470.8 518.9 531.2 547.9 562.8 4175.0 4204.8 4205.3 4281.3 4369.0 72378.0 116192.8 117292.0 121823.0 123074.0 89228.6 99289.1 116815.4 123702.8 124051.4 84130.2 94645.0 112569.0 113794.4 114012.4 7757.0 8154.7 8187.5 8230.8 8349.3 46146.6 51139.7 55245.2 61971.0 67209.7 89203.6 90270.0 91263.6 91710.5 92816.0 8999.3 11216.9 11412.2 11414.5 11515.8 12230.3 12245.9 12423.2 12447.1 12936.5 11649.0 12007.7 12019.2 12061.8 12411.4 11497.8 13200.7 13517.8 13579.4 14442.0 13246.5 13757.7 13803.1 13978.2 14126.0 10959.1 11124.4 11165.4 11558.9 11812.6 9544.6 9895.6 10186.9 10202.9 10314.8 16:15:20 INFO - PROCESS | 1647 | -------- Summary: end -------- 16:15:20 INFO - PROCESS | 1647 | 16:15:21 INFO - TEST-INFO | 1647: exit 0 16:15:21 INFO - TEST-OK | dromaeo_css | took 1116362ms 16:15:21 INFO - TEST-START | kraken 16:15:21 INFO - operating with platform_type : mac_ 16:15:21 INFO - Initialising browser for kraken test... 16:15:21 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpyHBpVx/profile http://localhost:49266/getInfo.html) 16:15:23 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 16:15:23 INFO - PROCESS | 1656 | colorDepth:24 16:15:23 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 16:15:23 INFO - PROCESS | 1656 | __metrics 16:15:23 INFO - PROCESS | 1656 | 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" 16:15:23 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:15:24 INFO - TEST-INFO | 1656: exit 0 16:15:24 INFO - Browser initialized. 16:15:24 INFO - Running cycle 1/1 for kraken test... 16:15:24 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpyHBpVx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:15:36 INFO - PROCESS | 1657 | RSS: Main: 243634176 16:15:36 INFO - PROCESS | 1657 | 16:15:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 16:15:39 INFO - PROCESS | 1657 | RSS: Main: 294559744 16:15:39 INFO - PROCESS | 1657 | 16:15:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 16:15:43 INFO - PROCESS | 1657 | RSS: Main: 304533504 16:15:43 INFO - PROCESS | 1657 | 16:15:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 16:15:47 INFO - PROCESS | 1657 | RSS: Main: 387469312 16:15:47 INFO - PROCESS | 1657 | 16:15:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 16:15:50 INFO - PROCESS | 1657 | RSS: Main: 289402880 16:15:50 INFO - PROCESS | 1657 | 16:15:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:15:52 INFO - PROCESS | 1657 | RSS: Main: 293134336 16:15:52 INFO - PROCESS | 1657 | 16:15:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 16:15:56 INFO - PROCESS | 1657 | RSS: Main: 330928128 16:15:56 INFO - PROCESS | 1657 | 16:15:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 16:15:59 INFO - PROCESS | 1657 | RSS: Main: 332750848 16:15:59 INFO - PROCESS | 1657 | 16:16:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 16:16:03 INFO - PROCESS | 1657 | RSS: Main: 331313152 16:16:03 INFO - PROCESS | 1657 | 16:16:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:16:05 INFO - PROCESS | 1657 | RSS: Main: 312107008 16:16:05 INFO - PROCESS | 1657 | 16:16:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:16:07 INFO - PROCESS | 1657 | RSS: Main: 319397888 16:16:07 INFO - PROCESS | 1657 | 16:16:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:16:10 INFO - PROCESS | 1657 | RSS: Main: 320323584 16:16:10 INFO - PROCESS | 1657 | 16:16:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:16:14 INFO - PROCESS | 1657 | RSS: Main: 326725632 16:16:14 INFO - PROCESS | 1657 | 16:16:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:16:17 INFO - PROCESS | 1657 | RSS: Main: 328712192 16:16:17 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 16:16:19 INFO - PROCESS | 1657 | RSS: Main: 371912704 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | __start_tp_report 16:16:19 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 16:16:19 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 16:16:19 INFO - PROCESS | 1657 | |i|pagename|runs| 16:16:19 INFO - PROCESS | 1657 | |0;ai-astar;100;113;112;113;113;112;114;114;112;113 16:16:19 INFO - PROCESS | 1657 | |1;audio-beat-detection;139;108;116;155;106;105;128;107;169;136 16:16:19 INFO - PROCESS | 1657 | |2;audio-dft;182;187;185;186;184;188;181;184;186;182 16:16:19 INFO - PROCESS | 1657 | |3;audio-fft;83;74;58;90;59;67;80;82;108;80 16:16:19 INFO - PROCESS | 1657 | |4;audio-oscillator;89;92;92;72;69;70;94;92;100;133 16:16:19 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;91;87;91;88;91;88;90;131;90;87 16:16:19 INFO - PROCESS | 1657 | |6;imaging-darkroom;98;96;96;96;95;97;96;96;94;92 16:16:19 INFO - PROCESS | 1657 | |7;imaging-desaturate;84;84;85;84;85;86;85;85;84;86 16:16:19 INFO - PROCESS | 1657 | |8;json-parse-financial;59;56;54;55;54;54;54;53;53;54 16:16:19 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;65;66;80;77;71;70;69;70;69;70 16:16:19 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;83;84;85;82;82;82;84;86;84;84 16:16:19 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;134;136;144;139;133;136;134;134;246;150 16:16:19 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;164;164;165;167;168;222;184;163;160;164 16:16:19 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;66;66;67;67;66;67;66;66;66;64 16:16:19 INFO - PROCESS | 1657 | __end_tp_report 16:16:19 INFO - PROCESS | 1657 | __start_cc_report 16:16:19 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2081 16:16:19 INFO - PROCESS | 1657 | __end_cc_report 16:16:19 INFO - PROCESS | 1657 | __startTimestamp1467242179945__endTimestamp 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | ------- Summary: start ------- 16:16:19 INFO - PROCESS | 1657 | Number of tests: 14 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:4.14 (3.7%) stddev-sans-first:0.78 16:16:19 INFO - PROCESS | 1657 | Values: 100.0 113.0 112.0 113.0 113.0 112.0 114.0 114.0 112.0 113.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:126.90 Median:132.00 stddev:22.56 (17.1%) stddev-sans-first:23.50 16:16:19 INFO - PROCESS | 1657 | Values: 139.0 108.0 116.0 155.0 106.0 105.0 128.0 107.0 169.0 136.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.50 stddev:2.32 (1.3%) stddev-sans-first:2.28 16:16:19 INFO - PROCESS | 1657 | Values: 182.0 187.0 185.0 186.0 184.0 188.0 181.0 184.0 186.0 182.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:78.10 Median:80.00 stddev:14.87 (18.6%) stddev-sans-first:15.67 16:16:19 INFO - PROCESS | 1657 | Values: 83.0 74.0 58.0 90.0 59.0 67.0 80.0 82.0 108.0 80.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:90.50 stddev:18.68 (20.6%) stddev-sans-first:19.81 16:16:19 INFO - PROCESS | 1657 | Values: 89.0 92.0 92.0 72.0 69.0 70.0 94.0 92.0 100.0 133.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:93.40 Median:90.00 stddev:13.31 (14.8%) stddev-sans-first:14.09 16:16:19 INFO - PROCESS | 1657 | Values: 91.0 87.0 91.0 88.0 91.0 88.0 90.0 131.0 90.0 87.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:1.65 (1.7%) stddev-sans-first:1.50 16:16:19 INFO - PROCESS | 1657 | Values: 98.0 96.0 96.0 96.0 95.0 97.0 96.0 96.0 94.0 92.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:0.79 (0.9%) stddev-sans-first:0.78 16:16:19 INFO - PROCESS | 1657 | Values: 84.0 84.0 85.0 84.0 85.0 86.0 85.0 85.0 84.0 86.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.00 stddev:1.78 (3.3%) stddev-sans-first:0.93 16:16:19 INFO - PROCESS | 1657 | Values: 59.0 56.0 54.0 55.0 54.0 54.0 54.0 53.0 53.0 54.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.00 stddev:4.57 (6.5%) stddev-sans-first:4.36 16:16:19 INFO - PROCESS | 1657 | Values: 65.0 66.0 80.0 77.0 71.0 70.0 69.0 70.0 69.0 70.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 16:16:19 INFO - PROCESS | 1657 | Values: 83.0 84.0 85.0 82.0 82.0 82.0 84.0 86.0 84.0 84.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:137.50 stddev:34.64 (25.2%) stddev-sans-first:36.34 16:16:19 INFO - PROCESS | 1657 | Values: 134.0 136.0 144.0 139.0 133.0 136.0 134.0 134.0 246.0 150.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:166.00 stddev:18.71 (11.3%) stddev-sans-first:19.62 16:16:19 INFO - PROCESS | 1657 | Values: 164.0 164.0 165.0 167.0 168.0 222.0 184.0 163.0 160.0 164.0 16:16:19 INFO - PROCESS | 1657 | 16:16:19 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 16:16:19 INFO - PROCESS | 1657 | Values: 66.0 66.0 67.0 67.0 66.0 67.0 66.0 66.0 66.0 64.0 16:16:19 INFO - PROCESS | 1657 | -------- Summary: end -------- 16:16:19 INFO - PROCESS | 1657 | 16:16:20 INFO - TEST-INFO | 1657: exit 0 16:16:20 INFO - TEST-OK | kraken | took 59396ms 16:16:20 INFO - SUITE-END | took 1175s 16:16:21 INFO - Completed test suite (00:19:36) 16:16:21 INFO - javascript benchmark 16:16:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.2888793353395, 3407.6081250656166, 3408.9147771305697, 3424.349373619013, 3486.54674892264, 4806.783694733736, 5583.246261754662, 5666.575006874491, 5735.311762588485, 5786.681732504705, 5713.942860571393, 5742.368339482654, 5807.73865176067, 5829.154772557977, 5830.0, 5124.256982737501, 5141.845744627649, 5194.766235519392, 5211.348581427322, 5338.439463856278, 5320.361556613212, 5338.386085600167, 5349.7057661828585, 5358.517733403993, 5396.973015134919, 5158.716270605134, 5217.47825217479, 5226.451222621608, 5262.605304602151, 5274.208868669692, 5158.3552055993005, 5165.22521621756, 5168.22476628505, 5269.262303277545, 5275.261463395128, 5109.463541276625, 5131.102057139986, 5184.9740751296, 5192.584593232532, 5243.79024839006, 5666.858328541819, 5737.856553586152, 5746.281714785652, 5770.682612456313, 5816.214811000503, 6156.415140561639, 6220.0, 6268.937310626927, 6274.090256912722, 6335.176427064498, 6273.1531243282025, 6280.811575652738, 6331.8733625327695, 6341.207349081365, 6384.0, 5687.1753595729215, 5868.178455016301, 5908.468237858613, 5927.673977931169, 5935.9406405935815, 6198.0702894565275, 6257.2491301044165, 6282.151909492251, 6300.086487459293, 6308.558400911892, 5146.845594632167, 6225.844353891189, 6232.719527621268, 6235.968820155961, 6237.282712488031, 6160.291566469913, 6256.655883926337, 6317.0, 6332.145160403379, 6353.269374022047, 6152.077188421773, 6189.6286222826775, 6207.34822843604, 6223.96888015566, 6295.401936815995, 6326.841828954313, 6328.240611126695, 6343.397377249154, 6351.0, 6355.014972679242, 4949.7030178189425, 4965.4041515018425, 5033.79864805411, 5045.217991211368, 5095.082885080721, 4509.661775366861, 4520.2315606347, 4550.294704320836, 4553.658475614342, 4570.611498022638, 5766.913496297559, 5783.88432231353, 5793.594448388572, 5796.362400135981, 5805.535557155417, 6244.12582238487, 6269.310435173802, 6303.0, 6351.17434733491, 6375.23497180332, 5792.565557583198, 5814.856869658697, 5822.883542329129, 5835.620684655484, 5867.970660146672, 5357.249985002025, 5440.047991601533, 5454.91817622736, 5460.754266058037, 5468.152436372368, 6239.937600623935, 6269.279032911182, 6284.277308109626, 6325.399087086719, 6353.14232578596, 6333.145025421509, 6336.239651241881, 6353.047042943595, 6361.713722882574, 6374.29882712892, 6237.033259844731, 6249.218847644044, 6297.433231009231, 6310.2743184534365, 6328.114064031131, 6101.065651476929, 6270.2475702915945, 6273.341299163704, 6287.339829317856, 6292.182016337847, 6327.462165715781, 6330.620162790247, 6333.809985700436, 6343.953247714076, 6363.90454143179, 4861.0, 5913.408659134052, 6119.265688117454, 6155.922713525205, 6176.227971503562, 5537.972310138423, 6116.694165291628, 6145.723442445012, 6165.352637972948, 6166.537509686702, 5932.940670593239, 5986.910196346971, 5995.820125396245, 6066.181065556093, 6069.878602428015, 5571.247881535941, 5901.29184497863, 6118.908216376847, 6133.969330153321, 6143.017117261216, 5986.07215881539, 5986.700664966821, 6002.549808764273, 6002.969985150046, 6007.519398448201], "value": 5732.395973486466, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.59775581778786, 517.878298599829, 537.7229080932784, 542.2381553916746, 562.1399259133525, 30172.094837154895, 40753.18493630126, 41554.58445415545, 41752.0, 41787.373189402184, 42587.36118958218, 42910.785446072805, 43000.0, 43135.0, 43246.56753432465, 8665.30677545798, 8845.867311990325, 9589.99305072966, 9731.0, 9737.269704772136, 8810.295176385906, 8904.975927768292, 8919.197272245498, 11668.066554675606, 11939.701507462296, 11867.406629668481, 11924.16530842837, 11933.16467847251, 11953.820692689616, 11962.82055769164, 11738.7652246955, 11878.0, 11883.0, 11891.643250702493, 11899.167058305966, 8593.312534997185, 11144.888551114465, 11389.886101139049, 11391.145664075228, 11393.259438136578, 40655.0, 41060.0, 41184.1763164735, 41195.76412707623, 41293.58706412927, 3523.4186359250693, 3544.6987006104255, 4086.4483294755423, 4088.1823635273063, 4237.978810105929, 31306.37387252242, 32490.512642310383, 32515.512267316008, 32582.511262331085, 32735.83632081824, 3160.336329370812, 3301.0756988043145, 3308.106811160973, 3327.93344133116, 3340.9665903340897, 516184.41907790594, 518801.0, 519778.0, 520559.7944020549, 527529.3623531896, 61330.0, 61478.0, 61927.6903615479, 62000.759984800054, 62170.68914655398, 64578.031329529156, 65021.0, 65397.67301163464, 65475.0, 65478.01782973259, 16413.42553010639, 16642.58393540171, 16672.583185420463, 16687.666246675, 16695.0, 11569.42152892352, 12679.23924564529, 12862.614121576367, 12932.159409638343, 12947.287899165449, 14689.41242350294, 15569.377224911103, 17000.48998530046, 17329.826701733073, 17454.040027798594, 6627.403533681895, 6650.467962562983, 6652.234992975773, 6685.431738302299, 6735.6295403753265, 927.4667066436825, 1004.7237009822358, 1059.380262546419, 1060.7984482948216, 1097.7969075721016, 29729.108126756233, 29904.55143172854, 30699.539506907415, 30926.22684432907, 32644.857429989836, 1029.0841151375282, 1033.328336581213, 1061.177587369779, 1077.3159044007111, 1099.8240281554913, 15449.565611806842, 15482.0, 15574.922125389527, 15740.527784166494, 16168.43410480651, 659010.0, 663195.6840215768, 663220.0, 664643.0, 665107.0, 58563.70718146382, 58581.4141858576, 58749.11876321858, 58841.0, 58949.0, 1151.7581307925261, 1247.79587697872, 1372.1149858341114, 1403.9087459315112, 1432.0977783996016, 30183.849080754455, 30872.69127308698, 31038.844805775825, 31489.055328340186, 31979.68020319767, 50522.24216636827, 51038.0, 51454.97090058103, 51479.0, 51698.22452663288, 451291.0, 452280.0, 454135.0, 454634.72682636377, 455973.0, 23616.17343392973, 26231.0, 26299.4740105193, 26586.335341616614, 26600.60099098554, 46847.0, 46903.76548117374, 47476.287855681505, 47684.80787980328, 47895.76052119717, 39239.607603924735, 39920.40119398153, 40142.39786403148, 40461.79769101136, 40675.57635482744, 38297.425538616386, 39696.801515992236, 40317.1936561273, 41059.97350066153, 41118.97202569959, 37975.810120949216, 38432.42351364676, 38506.80746596361, 39194.608053919095, 39438.2112357757, 29300.413991720474, 34716.47925281173, 34925.126871828405, 35311.11722206965, 35559.82220088883, 25510.0, 25838.224853254433, 25995.870020649774, 26039.088631897794, 26159.607605885547], "value": 20391.516315784036, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.5792365009288, 374.7451732821681, 383.30430269061634, 386.43001572680294, 402.9939550906735, 15699.764503532457, 16116.355345786169, 21065.894670526664, 21145.048472818722, 21232.0, 20840.270590529333, 20899.0, 21239.787602123975, 21282.680759788578, 21309.041093150805, 5529.0324193266215, 5654.632448890819, 5898.705064746767, 5922.763089476416, 5936.495397891184, 4819.879503012417, 5833.562482813784, 5899.557533185029, 5901.291844978608, 5909.261342332208, 5685.744141513642, 5805.187273781674, 5808.099744539582, 5817.41825817417, 5859.560532960024, 5771.3074431068335, 5805.564582656297, 5829.183914252008, 5831.300243970751, 5838.649681019152, 5122.564582010534, 5437.211604317391, 5437.945620543784, 5490.039243132436, 5496.4778346057465, 12823.679408014781, 13155.013373996893, 16416.589585260346, 23300.0, 24975.375615609755, 2035.3079952816113, 2164.5238047629496, 2283.394900351408, 2306.1006207579057, 2427.61814924062, 9716.708498745049, 10128.03783640553, 10196.0, 10906.345619262791, 15474.845251547453, 1787.9910600446915, 1793.3095758133213, 1837.338558119076, 1865.4590168851016, 1897.354899334233, 123610.0, 133848.0, 134156.0, 135233.0, 135303.32348338293, 11157.218994670295, 11274.323540587427, 11362.829557556643, 11451.484683189277, 11821.468033938494, 14069.94475414348, 14190.148591084568, 14206.644833879236, 14215.0, 14222.217778022308, 6689.698963546554, 6919.0, 8440.451370660887, 8461.915380846112, 8507.319414446829, 8316.251537361666, 8345.791355216168, 8370.288525475402, 8416.915830841735, 8506.064332923374, 4404.405405270248, 4495.707778994355, 4503.909921801545, 4516.593506584358, 4521.186186486399, 1623.602217456707, 1883.5761953560338, 1920.7611090846326, 1952.5509132899513, 1964.4695932098252, 913.5021413329752, 941.868138460616, 969.5879251318162, 975.5512464266517, 998.256299057207, 20905.686414703792, 21279.78720212809, 21350.679739803916, 21357.67963480549, 21407.67888481674, 935.6678379175431, 938.2775263047415, 962.5812771444444, 992.9337533192529, 999.6701088640731, 10532.46759678867, 10943.233973621771, 11046.46226532891, 11237.60668376603, 11335.263207891461, 597163.0, 599596.0, 605759.0, 607745.0, 608737.9563102245, 72141.0, 106313.46843265735, 134797.0, 137019.0, 140681.0, 969.5588507229194, 991.548845275398, 996.4818294486827, 1008.445355054717, 1013.1793247469574, 17376.0, 17779.0, 18780.967046812555, 19167.041647917828, 19181.040947952824, 55259.44740552704, 55353.72323138519, 55832.441675582726, 56376.0, 56548.15177772419, 77299.61350193214, 78545.4290914167, 78755.0, 81135.97160071046, 81411.59294203491, 6805.897911531232, 7884.250996155356, 7971.481853679492, 7990.720324788605, 8241.381896357678, 10588.364698118137, 12466.376681165724, 12535.0, 12696.555620553236, 12769.297688626843, 10745.409002504937, 10787.73030674208, 10923.235373523778, 10947.83578246311, 10966.287191332538, 10801.729956751144, 10988.175886808362, 11082.833757493483, 11154.163437742363, 11235.0, 10908.236423450282, 11145.0, 11154.721131971766, 11180.10559155282, 11365.261258018203, 6278.6546739929745, 7273.3817625500305, 7327.74352897646, 7331.340179383881, 7371.447141464519, 8005.9599702001115, 9744.561494732896, 10680.116368580284, 10915.83626245591, 10939.0], "value": 9393.666346565567, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.9728405431886, 1498.4680438444354, 1538.346202863783, 1713.2547341906272, 1893.5739458621813, 7645.885311720329, 8445.451045682026, 8645.913540864589, 8668.176523230299, 8719.4332368396, 8393.53835539045, 8610.827783444343, 8630.654773809056, 8638.17937295956, 8644.481331120138, 2076.49125964139, 2157.1802714968367, 2158.2769772126358, 2329.871857047862, 2358.5518751437257, 2122.0981083039724, 2133.722616059918, 2314.3057082875152, 2398.136670798511, 2399.2562305685206, 2342.1451170322794, 2351.7295511016196, 2353.376355265856, 2353.682252895863, 2354.776296251853, 2284.817214622826, 2321.1527792355837, 2334.5214231082673, 2335.065973610552, 2336.684547586071, 2103.2638576498257, 2305.3660243433023, 2374.7031621047367, 2378.025009746013, 2383.5947888858936, 8564.957175214084, 8622.870656940151, 8631.525266110362, 8634.309255259626, 8635.136486351315, 5155.74221288934, 5295.68225906443, 5312.6281160318795, 5312.6281160318795, 5319.946800531984, 3465.5841299044023, 3521.9647803521893, 3585.354636165489, 3649.9635003650155, 3657.5062366580705, 1027.291169093323, 1049.5801679328254, 1071.196602548089, 1075.6074032977906, 1076.0261962923566, 11424.771504569862, 11498.540058397653, 11505.884941150565, 11507.0, 11537.250078744855, 4482.932756008662, 4653.418322709661, 4766.737829419415, 4774.522547745198, 4778.354922085544, 9757.316987810786, 9793.265505087147, 9797.36317139398, 9804.509774511245, 9849.950750246346, 5308.203769434538, 5319.973400132974, 5321.414644389114, 5324.334458192726, 5328.973355133277, 4400.801963911632, 4750.95249047512, 4893.559579637849, 4900.068986892479, 4900.313956046157, 3045.8477076146105, 3713.0, 3824.961750382516, 3826.0052386379743, 3885.922281554353, 1159.2812456277163, 1163.697438665936, 1165.242592315002, 1173.7124404520775, 1179.0390831472323, 812.3460614205503, 868.9956550217335, 893.7765558610347, 924.2375040591539, 940.7036783413203, 5063.8480845574695, 5617.38208797032, 5645.9717701412055, 5904.5571582131515, 6010.669413182317, 895.1809094678297, 928.8250363290443, 944.3389627260807, 947.5972711597682, 971.223021582731, 3843.7501562398356, 3920.4511368408444, 3953.9802300988313, 4011.779352135602, 4044.676425885922, 10296.948515257525, 10547.630832920811, 10708.89291107079, 10714.73213169677, 10721.678349649523, 10076.59693612262, 10566.894331056745, 10648.0, 10662.893371066346, 10673.83989240162, 7920.168382319889, 8031.558264295403, 8206.384521160937, 8223.588820558949, 8226.341892648574, 5261.36863576358, 5293.835356221625, 5546.694931778791, 5580.972095139661, 5730.713464326684, 9580.281478888972, 9871.605135794487, 9918.05778451046, 9997.450140242065, 10013.148882345082, 10103.191744660262, 10209.642662506776, 10215.642452514126, 10217.693469195936, 10227.437490937773, 7073.080499535027, 7156.749513766993, 7164.0, 7167.498275120692, 7169.7849064528145, 7404.814879628052, 8112.472689275178, 8397.95801020991, 8418.915810841812, 8516.659333626585, 7387.335139837441, 7516.173220945582, 7524.548527088392, 7637.121731001006, 7642.961785191261, 7326.560406375635, 7334.596597187206, 7388.741394051182, 7424.517406368569, 7476.813079673052, 6546.738130474728, 6720.899186512109, 6728.192616886005, 6738.966305168443, 6756.45948324123, 4299.548547402478, 4722.504137065559, 4779.378680771478, 4898.853034408973, 4958.42978057528, 4302.268614335508, 4986.850394488026, 5019.52314530119, 5051.989602079525, 5146.716930568705], "value": 4505.532314507561, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.48628752526645, 301.7962875059335, 306.1626451654724, 309.9178717639826, 313.74116354007936, 4156.771377574232, 5237.3453318335205, 5244.0, 5267.604929630279, 5365.678059316444, 4196.971741923233, 4342.0, 4874.902501949959, 5247.825065835172, 5253.133233016548, 3751.1934933989187, 3907.355286377744, 3952.762834229941, 3984.366409771994, 4001.899952501182, 3469.7397695172835, 3547.503349531055, 3909.2181563687236, 3978.6021397860127, 3998.1808277233936, 3695.519582454277, 3936.940945885814, 3965.5637879833193, 3973.2649459849877, 3991.8203680834285, 3944.644981951624, 3973.7615743055508, 3977.34373828318, 4010.4786377770847, 4036.8385264589383, 3158.815444208422, 3231.5314279429585, 3302.719268862126, 3455.9827200863833, 3496.3531746626954, 4132.40080188371, 4999.675021123615, 5041.798328066873, 5046.0, 5047.520485553867, 4533.818647254106, 4535.365048893124, 4552.270137347819, 4570.200214962369, 4574.245249533823, 5038.949610503884, 5316.3620365556, 5438.265834112383, 5457.454254574551, 5468.44551041822, 3723.702103831714, 3745.3071181831447, 3752.7185461090526, 3756.267527832074, 3763.5295588051495, 4956.2653071425075, 5350.4649535046965, 5462.918056229159, 5558.138488534243, 5767.798127065575, 3028.9545656815167, 3712.5916149223563, 3744.120131769032, 3759.4360845872793, 3783.356829339019, 3489.900681285387, 3798.924021519554, 3820.3696390095324, 3821.4840996465678, 3823.655870971626, 3771.73597848148, 4363.280058790264, 4487.730736155841, 4500.437445319335, 4516.841910533116, 5493.258410114583, 5497.972510137423, 5519.503244707996, 5520.0, 5637.23897273871, 1290.2774446310095, 1306.4186437035619, 1309.9738005239842, 1313.0283590143226, 1313.487739781486, 761.8019314978035, 781.0471225105363, 799.7523862774091, 818.6725309876098, 823.3742355809628, 4000.0, 4198.929273035356, 4286.057067445157, 4326.459192600925, 4381.496127945264, 4211.578842115825, 4414.514403415622, 4604.539546045436, 4737.8341758039, 4870.464248932614, 3517.1734642359024, 3660.4143337065943, 4242.66058715302, 4260.786960651955, 4267.231898258281, 2454.96238122358, 2708.6072519484565, 2962.200205944383, 2962.4371369439737, 3014.80403773754, 4651.511516314747, 5383.730813459311, 5547.0, 5790.94209057904, 5877.972099625622, 4905.8037678492465, 5529.640573362719, 5816.621919575214, 5819.796307129315, 5852.414758524199, 4904.386951631046, 5168.9741551292, 5823.359430462646, 5838.365257727843, 5872.0, 4599.494055653809, 4702.48272689997, 5116.0, 5337.439568845215, 5627.493525582553, 4792.7124372537755, 4997.9500204997485, 5390.703511306916, 5614.63504872182, 5621.578381621476, 5269.314989051399, 5522.315693713456, 5689.800856969986, 5842.159719688285, 5856.531477481706, 3694.8706795262037, 3715.851365945332, 3774.9245015098995, 3779.5653499847867, 3806.069326618433, 5621.690807005654, 5636.943630563642, 5655.547556195576, 5658.104534980663, 5660.462256085665, 4393.648508119279, 4609.709281401213, 5283.75049344658, 5391.002664507078, 5447.754851031792, 4985.875353116201, 5402.9459705404015, 5404.891997140656, 5425.430329815313, 5425.593080518898, 4847.394075740533, 4853.845000749541, 5004.324416203766, 5081.907389911187, 5359.464053594609, 5006.723285562159, 5427.430119837518, 5441.591880608891, 5453.182022696627, 5483.396826349178, 4503.680604488756, 5303.840884773463, 5336.252924590638, 5354.171767186481, 5356.437574054824], "value": 3905.8674444335697, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.605304711079, 716.3302312337964, 760.1258552664439, 780.9297163255305, 788.9723859664913, 14827.925860370697, 16036.679266414694, 16038.037717736945, 16049.037057776512, 16128.596785080364, 14012.509562165325, 14360.569182924502, 14815.55553333399, 14860.851391486081, 14872.702545949074, 679.2290749998738, 700.6811900585247, 829.4276948905251, 842.3093063687778, 852.5353682243192, 824.2458150792019, 838.5723281126639, 838.8867502887093, 862.7584276402586, 865.3336930563462, 888.4092078767612, 891.1623074310128, 892.7411050795262, 894.4275663575316, 894.9105089491064, 641.8202903187115, 705.2806137739528, 888.1828717579842, 889.7686601483629, 889.8665200219954, 528.4081828352232, 534.8395481355577, 535.2319421629935, 635.9014352775328, 660.5112216959416, 14979.475718349806, 15075.321610527442, 15096.396144154221, 15105.0, 15117.0, 13670.043096983216, 13875.37560809766, 13968.301584920713, 14047.297635118202, 14292.142471451642, 11685.474153663106, 12583.937080314632, 12716.554920577735, 12843.03677224212, 13262.071654984155, 4486.371907932892, 4524.185646583614, 4629.768511574442, 4705.929411058837, 4738.668293219476, 110746.0, 113242.0, 114889.42555287253, 115772.99999999916, 116310.0, 10949.069329106957, 10960.440760411075, 11132.443377831076, 11235.66293011211, 11243.887561124448, 21913.123475061137, 21914.013869375598, 21922.561548768936, 21933.451663708536, 21949.231776888053, 7811.960940195377, 7821.687132514636, 7827.099883513355, 7893.407994400443, 7967.920320796834, 4061.4923134608175, 4092.1815636872743, 4123.299039163349, 4132.793360331972, 4134.028503301782, 3033.2871775132826, 3444.810535420576, 3537.239493508908, 3552.0054384772307, 3560.2345495718027, 627.5387590121258, 642.0593830039015, 649.5388274325283, 656.9934300656932, 666.1772710702279, 546.126198083064, 549.7690969792696, 565.7850016993599, 570.3355590736828, 587.6620942957817, 8404.663813447394, 8528.445651032787, 8644.394892357475, 8747.693830715894, 8785.69250076257, 470.7618962129577, 518.8806574487888, 531.1581143886905, 547.8904219156105, 562.8452175651647, 4175.0, 4204.768737719455, 4205.2640787862, 4281.293586558183, 4368.956310436855, 72378.0, 116192.83807161989, 117292.0, 121823.0, 123074.0, 89228.5538572303, 99289.07820139779, 116815.41592291984, 123702.76297237093, 124051.37974310252, 84130.15869841223, 94645.0, 112569.0, 113794.43102784599, 114012.42993784978, 7757.0, 8154.714584989497, 8187.4678145920325, 8230.794230144294, 8349.290310323691, 46146.61560153201, 51139.74430127825, 55245.171322431, 61971.0, 67209.66395167993, 89203.55398222967, 90270.0, 91263.63104553304, 91710.541447295, 92816.0, 8999.325050621099, 11216.887831121585, 11412.201145919706, 11414.48634811419, 11515.769684606428, 12230.327331996825, 12245.938770306091, 12423.192492487959, 12447.128700991207, 12936.477386909462, 11649.009834164191, 12007.699807504881, 12019.158658893793, 12061.819072714092, 12411.441485133371, 11497.82753258685, 13200.669983250496, 13517.797233041709, 13579.388927498088, 14442.0, 13246.536371226961, 13757.724845502833, 13803.102798318077, 13978.231197284247, 14126.0, 10959.123270138529, 11124.429167203343, 11165.441727913734, 11558.942205289257, 11812.586559470377, 9544.618215271312, 9895.554700038372, 10186.868536345519, 10202.863368188402, 10314.793704126025], "value": 7427.679190628899, "unit": "score"}], "name": "dromaeo_css", "value": 7235.987363297925, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 112.0, 113.0, 113.0, 112.0, 114.0, 114.0, 112.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 108.0, 116.0, 155.0, 106.0, 105.0, 128.0, 107.0, 169.0, 136.0], "value": 126.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 187.0, 185.0, 186.0, 184.0, 188.0, 181.0, 184.0, 186.0, 182.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 74.0, 58.0, 90.0, 59.0, 67.0, 80.0, 82.0, 108.0, 80.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 92.0, 72.0, 69.0, 70.0, 94.0, 92.0, 100.0, 133.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 91.0, 88.0, 91.0, 88.0, 90.0, 131.0, 90.0, 87.0], "value": 93.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 96.0, 96.0, 95.0, 97.0, 96.0, 96.0, 94.0, 92.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 85.0, 86.0, 85.0, 85.0, 84.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 54.0, 55.0, 54.0, 54.0, 54.0, 53.0, 53.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 80.0, 77.0, 71.0, 70.0, 69.0, 70.0, 69.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 82.0, 82.0, 82.0, 84.0, 86.0, 84.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 136.0, 144.0, 139.0, 133.0, 136.0, 134.0, 134.0, 246.0, 150.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 165.0, 167.0, 168.0, 222.0, 184.0, 163.0, 160.0, 164.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 64.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1460.8999999999996, "alertThreshold": 2.0}]} 16:16:21 INFO - Return code: 0 16:16:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:16:21 INFO - # TBPL SUCCESS # 16:16:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:16:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:16:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:16:21 INFO - 2016-06-29 16:16:21.256 screenresolution[1661:9670] starting screenresolution argv=screenresolution get 16:16:21 INFO - 2016-06-29 16:16:21.259 screenresolution[1661:9670] Display 0: 1600x1200x32@60 16:16:21 INFO - 2016-06-29 16:16:21.264 screenresolution[1662:9673] starting screenresolution argv=screenresolution list 16:16:21 INFO - Available Modes on Display 0 16:16:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:16:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:16:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:16:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:16:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:16:21 INFO - Intel Iris: 16:16:21 INFO - Chipset Model: Intel Iris 16:16:21 INFO - Type: GPU 16:16:21 INFO - Bus: Built-In 16:16:21 INFO - VRAM (Dynamic, Max): 1536 MB 16:16:21 INFO - Vendor: Intel (0x8086) 16:16:21 INFO - Device ID: 0x0a2e 16:16:21 INFO - Revision ID: 0x0009 16:16:21 INFO - Displays: 16:16:21 INFO - Display: 16:16:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:16:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:16:21 INFO - Display Serial Number: 1600x1200 60 16:16:21 INFO - Main Display: Yes 16:16:21 INFO - Mirror: Off 16:16:21 INFO - Online: Yes 16:16:21 INFO - Rotation: Supported 16:16:21 INFO - Return code: 0 16:16:21 INFO - Running post-action listener: _resource_record_post_action 16:16:21 INFO - ##### 16:16:21 INFO - ##### Finished run-tests step (success) 16:16:21 INFO - ##### 16:16:21 INFO - Running post-run listener: _resource_record_post_run 16:16:21 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37541376; Write bytes: 707176448; Read time: 387; Write time: 2060 16:16:21 INFO - TinderboxPrint: CPU usage
53.5% 16:16:21 INFO - TinderboxPrint: I/O read bytes / time
37,541,376 / 387 16:16:21 INFO - TinderboxPrint: I/O write bytes / time
707,176,448 / 2,060 16:16:21 INFO - TinderboxPrint: CPU idle
2,254.2 (46.5%) 16:16:21 INFO - TinderboxPrint: CPU system
969.2 (20.0%) 16:16:21 INFO - TinderboxPrint: CPU user
1,622.2 (33.5%) 16:16:21 INFO - TinderboxPrint: Swap in / out
444,153,856 / 0 16:16:21 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407951872; Write bytes: 410793984; Read time: 30827; Write time: 791 16:16:21 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 34387456; Write bytes: 294985728; Read time: 356; Write time: 1263 16:16:21 INFO - Running post-run listener: _upload_blobber_files 16:16:21 INFO - Blob upload gear active. 16:16:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:16:21 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 16:16:21 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'] 16:16:21 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 16:16:22 INFO - (blobuploader) - INFO - Open directory for files ... 16:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:16:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:23 INFO - (blobuploader) - INFO - Done attempting. 16:16:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:16:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:16:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:24 INFO - (blobuploader) - INFO - Done attempting. 16:16:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:16:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:16:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:26 INFO - (blobuploader) - INFO - Done attempting. 16:16:26 INFO - (blobuploader) - INFO - Iteration through files over. 16:16:26 INFO - Return code: 0 16:16:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:16:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:16:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7403d41d614f83cf5de81dd22ffd75e58ff19470f55c0cacecab30af98e2e88d81feae6b3c5ca29004ef1ee8bf58cf4b5096fcae0ffd359c3ce989c462eb1055", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49b7a8c0fa41b5870a480b0d02963402b498b31401d0f605f66412c280d25803d0df57271ed219a9baeb7826c85ccf9ac867ef90fe2843fb41b604cc64918364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4c64e5650b8c11e871ebf12032524b9ffefa596073dc9cbb80ba0355fbcef6811ca70deba4405d9cc028a8b9cb3f0053d56f932cabd85b6605ec7867b0db494"} 16:16:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:16:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:16:26 INFO - Contents: 16:16:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7403d41d614f83cf5de81dd22ffd75e58ff19470f55c0cacecab30af98e2e88d81feae6b3c5ca29004ef1ee8bf58cf4b5096fcae0ffd359c3ce989c462eb1055", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49b7a8c0fa41b5870a480b0d02963402b498b31401d0f605f66412c280d25803d0df57271ed219a9baeb7826c85ccf9ac867ef90fe2843fb41b604cc64918364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4c64e5650b8c11e871ebf12032524b9ffefa596073dc9cbb80ba0355fbcef6811ca70deba4405d9cc028a8b9cb3f0053d56f932cabd85b6605ec7867b0db494"} 16:16:26 INFO - Running post-run listener: copy_logs_to_upload_dir 16:16:26 INFO - Copying logs to upload dir... 16:16:26 INFO - mkdir: /builds/slave/test/build/upload/logs 16:16:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.787161 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-06-29 16:16:26.775431) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 16:16:26.778698) ========= 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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/7403d41d614f83cf5de81dd22ffd75e58ff19470f55c0cacecab30af98e2e88d81feae6b3c5ca29004ef1ee8bf58cf4b5096fcae0ffd359c3ce989c462eb1055", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49b7a8c0fa41b5870a480b0d02963402b498b31401d0f605f66412c280d25803d0df57271ed219a9baeb7826c85ccf9ac867ef90fe2843fb41b604cc64918364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4c64e5650b8c11e871ebf12032524b9ffefa596073dc9cbb80ba0355fbcef6811ca70deba4405d9cc028a8b9cb3f0053d56f932cabd85b6605ec7867b0db494"} build_url:https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010820 build_url: 'https://queue.taskcluster.net/v1/task/Uf0syrceTtGdhpJseBcdyA/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/7403d41d614f83cf5de81dd22ffd75e58ff19470f55c0cacecab30af98e2e88d81feae6b3c5ca29004ef1ee8bf58cf4b5096fcae0ffd359c3ce989c462eb1055", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49b7a8c0fa41b5870a480b0d02963402b498b31401d0f605f66412c280d25803d0df57271ed219a9baeb7826c85ccf9ac867ef90fe2843fb41b604cc64918364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4c64e5650b8c11e871ebf12032524b9ffefa596073dc9cbb80ba0355fbcef6811ca70deba4405d9cc028a8b9cb3f0053d56f932cabd85b6605ec7867b0db494"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 16:16:26.811178) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:16:26.811523) ========= 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.xDhTEjxZC7/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.VwXynJ4Wir/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005186 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:16:26.860023) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 16:16:26.860364) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 16:16:26.860715) ========= ========= Total master_lag: 0.49 =========