builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0065 starttime: 1460995159.53 results: success (0) buildid: 20160418060925 builduid: 87ea3806ed8e484c93c35644f50afbf1 revision: 8975734f1546f7ab475037441e9a0591af299e32 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 08:59:19.526733) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 08:59:19.527113) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-04-18 08:59:19.527381) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-04-18 08:59:23.818949) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-18 08:59:23.819228) ========= 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.kEt4FBCXnT/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.l79qhjm7wI/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-18 08:59:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 69.8M=0s 2016-04-18 08:59:23 (69.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117551 ========= master_lag: 3.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-18 08:59:27.112745) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 08:59:27.113072) ========= 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.kEt4FBCXnT/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.l79qhjm7wI/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.019855 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 08:59:27.153561) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 08:59:27.153901) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8975734f1546f7ab475037441e9a0591af299e32 --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 8975734f1546f7ab475037441e9a0591af299e32 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kEt4FBCXnT/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.l79qhjm7wI/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-18 08:59:27,232 truncating revision to first 12 chars 2016-04-18 08:59:27,232 Setting DEBUG logging. 2016-04-18 08:59:27,232 attempt 1/10 2016-04-18 08:59:27,232 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8975734f1546?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 08:59:28,675 unpacking tar archive at: fx-team-8975734f1546/testing/mozharness/ program finished with exit code 0 elapsedTime=1.692813 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 08:59:28.863562) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 08:59:28.866905) ========= script_repo_revision: 8975734f1546f7ab475037441e9a0591af299e32 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 08:59:28.867310) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 08:59:28.867580) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 08:59:28.884261) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-18 08:59:28.884537) ========= /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.kEt4FBCXnT/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.l79qhjm7wI/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:59:29 INFO - MultiFileLogger online at 20160418 08:59:29 in /builds/slave/test 08:59:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:59:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:29 INFO - {'append_to_log': False, 08:59:29 INFO - 'base_work_dir': '/builds/slave/test', 08:59:29 INFO - 'blob_upload_branch': 'Fx-Team', 08:59:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:29 INFO - 'branch': 'Fx-Team', 08:59:29 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:29 INFO - 'config_files': ('talos/mac_config.py',), 08:59:29 INFO - 'default_actions': ('clobber', 08:59:29 INFO - 'read-buildbot-config', 08:59:29 INFO - 'download-and-extract', 08:59:29 INFO - 'populate-webroot', 08:59:29 INFO - 'create-virtualenv', 08:59:29 INFO - 'install', 08:59:29 INFO - 'run-tests'), 08:59:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:29 INFO - 'download_minidump_stackwalk': True, 08:59:29 INFO - 'download_symbols': 'ondemand', 08:59:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:29 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:29 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:29 INFO - 'installer_path': 'installer.exe', 08:59:29 INFO - 'log_level': 'info', 08:59:29 INFO - 'log_name': 'talos', 08:59:29 INFO - 'log_to_console': True, 08:59:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:59:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:59:29 INFO - 'opt_config_files': (), 08:59:29 INFO - 'pip_index': False, 08:59:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:29 INFO - 'cmd': ('bash', 08:59:29 INFO - '-c', 08:59:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:29 INFO - 'enabled': True, 08:59:29 INFO - 'halt_on_failure': False, 08:59:29 INFO - 'name': 'check_screen_resolution'},), 08:59:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:29 INFO - 'cmd': ('bash', 08:59:29 INFO - '-c', 08:59:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:29 INFO - 'enabled': True, 08:59:29 INFO - 'halt_on_failure': False, 08:59:29 INFO - 'name': 'check_screen_resolution'},), 08:59:29 INFO - 'run_cmd_checks_enabled': True, 08:59:29 INFO - 'sps_profile': False, 08:59:29 INFO - 'sps_profile_interval': 0, 08:59:29 INFO - 'suite': 'dromaeojs', 08:59:29 INFO - 'system_bits': '32', 08:59:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:29 INFO - 'title': 't-yosemite-r7-0065', 08:59:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:29 INFO - 'use_talos_json': True, 08:59:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:29 INFO - 'work_dir': 'build'} 08:59:29 INFO - ##### 08:59:29 INFO - ##### Running clobber step. 08:59:29 INFO - ##### 08:59:29 INFO - Running pre-action listener: _resource_record_pre_action 08:59:29 INFO - Running main action method: clobber 08:59:29 INFO - rmtree: /builds/slave/test/build 08:59:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:29 INFO - Running post-action listener: _resource_record_post_action 08:59:29 INFO - ##### 08:59:29 INFO - ##### Running read-buildbot-config step. 08:59:29 INFO - ##### 08:59:29 INFO - Running pre-action listener: _resource_record_pre_action 08:59:29 INFO - Running main action method: read_buildbot_config 08:59:29 INFO - Using buildbot properties: 08:59:29 INFO - { 08:59:29 INFO - "project": "", 08:59:29 INFO - "product": "firefox", 08:59:29 INFO - "script_repo_revision": "production", 08:59:29 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:59:29 INFO - "repository": "", 08:59:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:59:29 INFO - "buildid": "20160418060925", 08:59:29 INFO - "pgo_build": "False", 08:59:29 INFO - "basedir": "/builds/slave/test", 08:59:29 INFO - "buildnumber": 673, 08:59:29 INFO - "slavename": "t-yosemite-r7-0065", 08:59:29 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:59:29 INFO - "platform": "yosemite_r7", 08:59:29 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:59:29 INFO - "branch": "fx-team", 08:59:29 INFO - "repo_path": "integration/fx-team", 08:59:29 INFO - "revision": "8975734f1546f7ab475037441e9a0591af299e32", 08:59:29 INFO - "stage_platform": "macosx64", 08:59:29 INFO - "builduid": "87ea3806ed8e484c93c35644f50afbf1", 08:59:29 INFO - "slavebuilddir": "test" 08:59:29 INFO - } 08:59:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 08:59:29 INFO - Running post-action listener: _resource_record_post_action 08:59:29 INFO - ##### 08:59:29 INFO - ##### Running download-and-extract step. 08:59:29 INFO - ##### 08:59:29 INFO - Running pre-action listener: _resource_record_pre_action 08:59:29 INFO - Running main action method: download_and_extract 08:59:29 INFO - mkdir: /builds/slave/test/build/tests 08:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:29 INFO - https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:59:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:59:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:59:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:59:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 08:59:30 INFO - Downloaded 1199 bytes. 08:59:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:59:30 INFO - Using the following test package requirements: 08:59:30 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:59:30 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:59:30 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 08:59:30 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:59:30 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 08:59:30 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:59:30 INFO - u'jsshell-mac.zip'], 08:59:30 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:59:30 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 08:59:30 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:59:30 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:59:30 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 08:59:30 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:59:30 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 08:59:30 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:59:30 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 08:59:30 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:59:30 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 08:59:30 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:30 INFO - https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:59:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:59:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:59:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:59:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:59:31 INFO - Downloaded 18032139 bytes. 08:59:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:59:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:59:35 INFO - Return code: 0 08:59:35 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:35 INFO - https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:59:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:59:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:59:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:59:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:59:36 INFO - Downloaded 18032139 bytes. 08:59:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:59:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:59:39 INFO - Return code: 0 08:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:39 INFO - https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:59:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:59:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:59:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:59:41 INFO - Downloaded 10845952 bytes. 08:59:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:59:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:59:41 INFO - Return code: 0 08:59: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')]} 08:59:41 INFO - https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:59:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:59:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:59:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 08:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 08:59:46 INFO - Downloaded 109967086 bytes. 08:59:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:59:46 INFO - mkdir: /builds/slave/test/properties 08:59:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:59:46 INFO - Writing to file /builds/slave/test/properties/build_url 08:59:46 INFO - Contents: 08:59:46 INFO - build_url:https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:59:47 INFO - Running post-action listener: _resource_record_post_action 08:59:47 INFO - Running post-action listener: set_extra_try_arguments 08:59:47 INFO - ##### 08:59:47 INFO - ##### Running populate-webroot step. 08:59:47 INFO - ##### 08:59:47 INFO - Running pre-action listener: _resource_record_pre_action 08:59:47 INFO - Running main action method: populate_webroot 08:59:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:59:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:59:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:59:47 INFO - '--tppagecycles', 08:59:47 INFO - '7'], 08:59:47 INFO - 'tests': ['tsvgm']}}, 08:59:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:59:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:59:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:59:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:59:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:47 INFO - 'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:47 INFO - 'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['damp', 'tps']}, 08:59:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:47 INFO - 'tests': ['damp', 'tps']}, 08:59:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['dromaeo_dom']}, 08:59:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:59:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['a11yr', 08:59:47 INFO - 'ts_paint', 08:59:47 INFO - 'tpaint', 08:59:47 INFO - 'sessionrestore', 08:59:47 INFO - 'sessionrestore_no_auto_restore', 08:59:47 INFO - 'tabpaint']}, 08:59:47 INFO - 'other-e10s': {'tests': ['a11yr', 08:59:47 INFO - 'ts_paint', 08:59:47 INFO - 'tpaint', 08:59:47 INFO - 'sessionrestore', 08:59:47 INFO - 'sessionrestore_no_auto_restore', 08:59:47 INFO - 'tabpaint']}, 08:59:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:59:47 INFO - 'ts_paint', 08:59:47 INFO - 'tpaint', 08:59:47 INFO - 'sessionrestore', 08:59:47 INFO - 'sessionrestore_no_auto_restore', 08:59:47 INFO - 'tabpaint']}, 08:59:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:59:47 INFO - 'ts_paint', 08:59:47 INFO - 'tpaint', 08:59:47 INFO - 'sessionrestore', 08:59:47 INFO - 'sessionrestore_no_auto_restore', 08:59:47 INFO - 'tabpaint']}, 08:59:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['a11yr', 08:59:47 INFO - 'ts_paint', 08:59:47 INFO - 'tpaint', 08:59:47 INFO - 'sessionrestore', 08:59:47 INFO - 'sessionrestore_no_auto_restore', 08:59:47 INFO - 'tabpaint']}, 08:59:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['a11yr', 08:59:47 INFO - 'ts_paint', 08:59:47 INFO - 'tpaint', 08:59:47 INFO - 'sessionrestore', 08:59:47 INFO - 'sessionrestore_no_auto_restore', 08:59:47 INFO - 'tabpaint']}, 08:59:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['tsvgx', 08:59:47 INFO - 'tsvgr_opacity', 08:59:47 INFO - 'tart', 08:59:47 INFO - 'tscrollx', 08:59:47 INFO - 'cart']}, 08:59:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:59:47 INFO - 'tsvgr_opacity', 08:59:47 INFO - 'tart', 08:59:47 INFO - 'tscrollx', 08:59:47 INFO - 'cart']}, 08:59:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:47 INFO - 'talos_options': ['--disable-e10s'], 08:59:47 INFO - 'tests': ['tp5o']}, 08:59:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:47 INFO - 'tests': ['tp5o']}, 08:59:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:47 INFO - 'talos_options': ['--disable-e10s', 08:59:47 INFO - '--xperf_path', 08:59:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:47 INFO - 'tests': ['tp5n']}, 08:59:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:59:47 INFO - 'talos_options': ['--xperf_path', 08:59:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:47 INFO - 'tests': ['tp5n']}}, 08:59:47 INFO - 'talos.zip': {'path': '', 08:59:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:59:47 INFO - Running post-action listener: _resource_record_post_action 08:59:47 INFO - ##### 08:59:47 INFO - ##### Running create-virtualenv step. 08:59:47 INFO - ##### 08:59:47 INFO - Running pre-action listener: _resource_record_pre_action 08:59:47 INFO - Running main action method: create_virtualenv 08:59:47 INFO - Creating virtualenv /builds/slave/test/build/venv 08:59:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:59:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:59:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:59:47 INFO - Using real prefix '/tools/python27' 08:59:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:59:48 INFO - Installing distribute.............................................................................................................................................................................................done. 08:59:51 INFO - Installing pip.................done. 08:59:51 INFO - Return code: 0 08:59:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:59:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 08:59:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:51 INFO - 'HOME': '/Users/cltbld', 08:59:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:51 INFO - 'LOGNAME': 'cltbld', 08:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:51 INFO - 'MOZ_NO_REMOTE': '1', 08:59:51 INFO - 'NO_EM_RESTART': '1', 08:59:51 INFO - 'PAGER': '/bin/cat', 08:59:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:51 INFO - 'PWD': '/builds/slave/test', 08:59:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:51 INFO - 'SHELL': '/bin/bash', 08:59:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 08:59:51 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 08:59:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:51 INFO - 'USER': 'cltbld', 08:59:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:51 INFO - 'XPC_FLAGS': '0x0', 08:59:51 INFO - 'XPC_SERVICE_NAME': '0', 08:59:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:59:51 INFO - Cleaning up... 08:59:51 INFO - Return code: 0 08:59:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 08:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:59:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 08:59:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:51 INFO - 'HOME': '/Users/cltbld', 08:59:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:51 INFO - 'LOGNAME': 'cltbld', 08:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:51 INFO - 'MOZ_NO_REMOTE': '1', 08:59:51 INFO - 'NO_EM_RESTART': '1', 08:59:51 INFO - 'PAGER': '/bin/cat', 08:59:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:51 INFO - 'PWD': '/builds/slave/test', 08:59:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:51 INFO - 'SHELL': '/bin/bash', 08:59:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 08:59:51 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 08:59:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:51 INFO - 'USER': 'cltbld', 08:59:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:51 INFO - 'XPC_FLAGS': '0x0', 08:59:51 INFO - 'XPC_SERVICE_NAME': '0', 08:59:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:51 INFO - Downloading/unpacking psutil>=0.7.1 08:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:59:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:54 INFO - Installing collected packages: psutil 08:59:54 INFO - Running setup.py install for psutil 08:59:54 INFO - building 'psutil._psutil_osx' extension 08:59:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:59:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:59:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:59:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:59:55 INFO - building 'psutil._psutil_posix' extension 08:59:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:59:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:59:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:59:55 INFO - ^ 08:59:55 INFO - 1 warning generated. 08:59:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:59:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:55 INFO - Successfully installed psutil 08:59:55 INFO - Cleaning up... 08:59:55 INFO - Return code: 0 08:59:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:59:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 08:59:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:55 INFO - 'HOME': '/Users/cltbld', 08:59:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:55 INFO - 'LOGNAME': 'cltbld', 08:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:55 INFO - 'MOZ_NO_REMOTE': '1', 08:59:55 INFO - 'NO_EM_RESTART': '1', 08:59:55 INFO - 'PAGER': '/bin/cat', 08:59:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:55 INFO - 'PWD': '/builds/slave/test', 08:59:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:55 INFO - 'SHELL': '/bin/bash', 08:59:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 08:59:55 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 08:59:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:55 INFO - 'USER': 'cltbld', 08:59:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:55 INFO - 'XPC_FLAGS': '0x0', 08:59:55 INFO - 'XPC_SERVICE_NAME': '0', 08:59:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:59:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:59:58 INFO - Installing collected packages: mozsystemmonitor 08:59:58 INFO - Running setup.py install for mozsystemmonitor 08:59:58 INFO - Successfully installed mozsystemmonitor 08:59:58 INFO - Cleaning up... 08:59:58 INFO - Return code: 0 08:59:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:59: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')]} 08:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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')]} 08:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:58 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 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:59:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:59:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 08:59:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:58 INFO - 'HOME': '/Users/cltbld', 08:59:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:58 INFO - 'LOGNAME': 'cltbld', 08:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:58 INFO - 'MOZ_NO_REMOTE': '1', 08:59:58 INFO - 'NO_EM_RESTART': '1', 08:59:58 INFO - 'PAGER': '/bin/cat', 08:59:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:58 INFO - 'PWD': '/builds/slave/test', 08:59:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:58 INFO - 'SHELL': '/bin/bash', 08:59:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 08:59:58 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 08:59:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:58 INFO - 'USER': 'cltbld', 08:59:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:58 INFO - 'XPC_FLAGS': '0x0', 08:59:58 INFO - 'XPC_SERVICE_NAME': '0', 08:59:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:58 INFO - Downloading/unpacking blobuploader==1.2.4 08:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:58 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 09:00:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:01 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 09:00:01 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 09:00:01 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 09:00:01 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 09:00:01 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 09:00:01 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 09:00:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:02 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 09:00:02 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 09:00:02 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 09:00:02 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 09:00:02 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 09:00:02 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 09:00:02 INFO - Downloading docopt-0.6.1.tar.gz 09:00:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:02 INFO - Running setup.py install for blobuploader 09:00:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:02 INFO - Running setup.py install for requests 09:00:03 INFO - Running setup.py install for docopt 09:00:03 INFO - Successfully installed blobuploader requests docopt 09:00:03 INFO - Cleaning up... 09:00:03 INFO - Return code: 0 09:00:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:03 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 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 09:00:03 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 09:00:03 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 09:00:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 09:00:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:03 INFO - 'HOME': '/Users/cltbld', 09:00:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:03 INFO - 'LOGNAME': 'cltbld', 09:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:03 INFO - 'MOZ_NO_REMOTE': '1', 09:00:03 INFO - 'NO_EM_RESTART': '1', 09:00:03 INFO - 'PAGER': '/bin/cat', 09:00:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:03 INFO - 'PWD': '/builds/slave/test', 09:00:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:03 INFO - 'SHELL': '/bin/bash', 09:00:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 09:00:03 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 09:00:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:03 INFO - 'USER': 'cltbld', 09:00:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:03 INFO - 'XPC_FLAGS': '0x0', 09:00:03 INFO - 'XPC_SERVICE_NAME': '0', 09:00:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:03 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-AKwTo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-K_50RI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-x9bKtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Wmvuzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-jtYnpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-jhmJVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-UuWEqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-__4FFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-AmCo6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:04 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-peUW4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:05 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-XiSPIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:05 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-p4mqm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:05 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-YfaaGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:05 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-rdw1IE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:05 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-l6hVCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:05 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-kb4q9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:05 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-BQ9ejJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:05 INFO - Running setup.py install for manifestparser 09:00:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:05 INFO - Running setup.py install for mozcrash 09:00:06 INFO - Running setup.py install for mozdebug 09:00:06 INFO - Running setup.py install for mozdevice 09:00:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:06 INFO - Running setup.py install for mozfile 09:00:06 INFO - Running setup.py install for mozhttpd 09:00:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:06 INFO - Running setup.py install for mozinfo 09:00:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:06 INFO - Running setup.py install for mozInstall 09:00:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:06 INFO - Running setup.py install for mozleak 09:00:07 INFO - Running setup.py install for mozlog 09:00:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:07 INFO - Running setup.py install for moznetwork 09:00:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:07 INFO - Running setup.py install for mozprocess 09:00:07 INFO - Running setup.py install for mozprofile 09:00:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:07 INFO - Running setup.py install for mozrunner 09:00:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:07 INFO - Running setup.py install for mozscreenshot 09:00:07 INFO - Running setup.py install for moztest 09:00:08 INFO - Running setup.py install for mozversion 09:00:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:08 INFO - Cleaning up... 09:00:08 INFO - Return code: 0 09:00:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:08 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 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 09:00:08 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 09:00:08 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 09:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 09:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:08 INFO - 'HOME': '/Users/cltbld', 09:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:08 INFO - 'LOGNAME': 'cltbld', 09:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:08 INFO - 'MOZ_NO_REMOTE': '1', 09:00:08 INFO - 'NO_EM_RESTART': '1', 09:00:08 INFO - 'PAGER': '/bin/cat', 09:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:08 INFO - 'PWD': '/builds/slave/test', 09:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:08 INFO - 'SHELL': '/bin/bash', 09:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 09:00:08 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 09:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:08 INFO - 'USER': 'cltbld', 09:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:08 INFO - 'XPC_FLAGS': '0x0', 09:00:08 INFO - 'XPC_SERVICE_NAME': '0', 09:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:08 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-rOVTwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:08 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)) 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:08 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-8H7o7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:08 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)) 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:08 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-OCuw11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:08 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)) 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:08 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-etWAkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:08 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)) 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:08 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-H4yoHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:09 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)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-LViNGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:09 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)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-kbxAh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:09 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)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-vsxry6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:09 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)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-QjjHle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:09 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)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-MNbSxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-ZXg8zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:09 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)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-WvxxYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:09 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)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-ruwvkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:09 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)) 09:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-IrLeV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:10 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)) 09:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-CtRMnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:10 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)) 09:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-dHiXEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:10 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)) 09:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-phcUIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:10 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)) 09:00:10 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)) 09:00:10 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)) 09:00:10 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)) 09:00:10 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)) 09:00:10 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)) 09:00:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:13 INFO - Downloading blessings-1.6.tar.gz 09:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:13 INFO - Installing collected packages: blessings 09:00:13 INFO - Running setup.py install for blessings 09:00:13 INFO - Successfully installed blessings 09:00:13 INFO - Cleaning up... 09:00:13 INFO - Return code: 0 09:00:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:13 INFO - Reading from file tmpfile_stdout 09:00:13 INFO - Current package versions: 09:00:13 INFO - blessings == 1.6 09:00:13 INFO - blobuploader == 1.2.4 09:00:13 INFO - docopt == 0.6.1 09:00:13 INFO - manifestparser == 1.1 09:00:13 INFO - mozInstall == 1.12 09:00:13 INFO - mozcrash == 0.17 09:00:13 INFO - mozdebug == 0.1 09:00:13 INFO - mozdevice == 0.48 09:00:13 INFO - mozfile == 1.2 09:00:13 INFO - mozhttpd == 0.7 09:00:13 INFO - mozinfo == 0.9 09:00:13 INFO - mozleak == 0.1 09:00:13 INFO - mozlog == 3.1 09:00:13 INFO - moznetwork == 0.27 09:00:13 INFO - mozprocess == 0.22 09:00:13 INFO - mozprofile == 0.28 09:00:13 INFO - mozrunner == 6.11 09:00:13 INFO - mozscreenshot == 0.1 09:00:13 INFO - mozsystemmonitor == 0.0 09:00:13 INFO - moztest == 0.7 09:00:13 INFO - mozversion == 1.4 09:00:13 INFO - psutil == 3.1.1 09:00:13 INFO - requests == 1.2.3 09:00:13 INFO - wsgiref == 0.1.2 09:00:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:14 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 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 09:00:14 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 09:00:14 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 09:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 09:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:14 INFO - 'HOME': '/Users/cltbld', 09:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:14 INFO - 'LOGNAME': 'cltbld', 09:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:14 INFO - 'MOZ_NO_REMOTE': '1', 09:00:14 INFO - 'NO_EM_RESTART': '1', 09:00:14 INFO - 'PAGER': '/bin/cat', 09:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:14 INFO - 'PWD': '/builds/slave/test', 09:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:14 INFO - 'SHELL': '/bin/bash', 09:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 09:00:14 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 09:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:14 INFO - 'USER': 'cltbld', 09:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:14 INFO - 'XPC_FLAGS': '0x0', 09:00:14 INFO - 'XPC_SERVICE_NAME': '0', 09:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 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)) 09:00:14 INFO - Cleaning up... 09:00:14 INFO - Return code: 0 09:00:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:14 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 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 09:00:14 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 09:00:14 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 09:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 09:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:14 INFO - 'HOME': '/Users/cltbld', 09:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:14 INFO - 'LOGNAME': 'cltbld', 09:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:14 INFO - 'MOZ_NO_REMOTE': '1', 09:00:14 INFO - 'NO_EM_RESTART': '1', 09:00:14 INFO - 'PAGER': '/bin/cat', 09:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:14 INFO - 'PWD': '/builds/slave/test', 09:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:14 INFO - 'SHELL': '/bin/bash', 09:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 09:00:14 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 09:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:14 INFO - 'USER': 'cltbld', 09:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:14 INFO - 'XPC_FLAGS': '0x0', 09:00:14 INFO - 'XPC_SERVICE_NAME': '0', 09:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:14 INFO - Downloading/unpacking jsonschema 09:00:14 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 09:00:14 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 09:00:14 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 09:00:14 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 09:00:14 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 09:00:14 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 09:00:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:17 INFO - Downloading/unpacking functools32 (from jsonschema) 09:00:17 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 09:00:17 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 09:00:17 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 09:00:17 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 09:00:17 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 09:00:17 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 09:00:17 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:17 INFO - warning: no files found matching '*.txt' 09:00:17 INFO - no previously-included directories found matching 'build' 09:00:17 INFO - no previously-included directories found matching 'dist' 09:00:17 INFO - no previously-included directories found matching '.git*' 09:00:17 INFO - Installing collected packages: jsonschema, functools32 09:00:17 INFO - Running setup.py install for functools32 09:00:17 INFO - warning: no files found matching '*.txt' 09:00:17 INFO - no previously-included directories found matching 'build' 09:00:17 INFO - no previously-included directories found matching 'dist' 09:00:17 INFO - no previously-included directories found matching '.git*' 09:00:17 INFO - Successfully installed jsonschema functools32 09:00:17 INFO - Cleaning up... 09:00:17 INFO - Return code: 0 09:00:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:00:17 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')]} 09:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 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')]} 09:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe82495420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, '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.kEt4FBCXnT/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.l79qhjm7wI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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 09:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 09:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:17 INFO - 'HOME': '/Users/cltbld', 09:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:17 INFO - 'LOGNAME': 'cltbld', 09:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:17 INFO - 'MOZ_NO_REMOTE': '1', 09:00:17 INFO - 'NO_EM_RESTART': '1', 09:00:17 INFO - 'PAGER': '/bin/cat', 09:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:17 INFO - 'PWD': '/builds/slave/test', 09:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:17 INFO - 'SHELL': '/bin/bash', 09:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 09:00:17 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 09:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:17 INFO - 'USER': 'cltbld', 09:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:17 INFO - 'XPC_FLAGS': '0x0', 09:00:17 INFO - 'XPC_SERVICE_NAME': '0', 09:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:18 INFO - Downloading/unpacking flake8 09:00:18 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 09:00:18 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 09:00:18 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 09:00:18 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 09:00:18 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 09:00:18 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 09:00:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:00:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:00:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 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 09:00:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:00:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:21 INFO - no previously-included directories found matching 'docs/_build' 09:00:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:00:21 INFO - Running setup.py install for pep8 09:00:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:21 INFO - no previously-included directories found matching 'docs/_build' 09:00:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:00:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:00:21 INFO - Cleaning up... 09:00:22 INFO - Return code: 0 09:00:22 INFO - Running post-action listener: _resource_record_post_action 09:00:22 INFO - Running post-action listener: _start_resource_monitoring 09:00:22 INFO - Starting resource monitoring. 09:00:22 INFO - ##### 09:00:22 INFO - ##### Running install step. 09:00:22 INFO - ##### 09:00:22 INFO - Running pre-action listener: _resource_record_pre_action 09:00:22 INFO - Running main action method: install 09:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:22 INFO - Reading from file tmpfile_stdout 09:00:22 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:22 INFO - Reading from file tmpfile_stdout 09:00:22 INFO - Output received: 09:00:22 INFO - Usage: mozinstall [options] installer 09:00:22 INFO - Options: 09:00:22 INFO - -h, --help show this help message and exit 09:00:22 INFO - -d DEST, --destination=DEST 09:00:22 INFO - Directory to install application into. [default: 09:00:22 INFO - "/builds/slave/test"] 09:00:22 INFO - --app=APP Application being installed. [default: firefox] 09:00:22 INFO - mkdir: /builds/slave/test/build/application 09:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:00:56 INFO - Reading from file tmpfile_stdout 09:00:56 INFO - Output received: 09:00:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:00:56 INFO - Running post-action listener: _resource_record_post_action 09:00:56 INFO - ##### 09:00:56 INFO - ##### Running run-tests step. 09:00:56 INFO - ##### 09:00:56 INFO - Running pre-action listener: _resource_record_pre_action 09:00:56 INFO - Running main action method: run_tests 09:00:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:00:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:00:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:00:56 INFO - 2016-04-18 09:00:56.075 screenresolution[6137:102261] starting screenresolution argv=screenresolution get 09:00:56 INFO - 2016-04-18 09:00:56.077 screenresolution[6137:102261] Display 0: 1600x1200x32@60 09:00:56 INFO - 2016-04-18 09:00:56.084 screenresolution[6138:102263] starting screenresolution argv=screenresolution list 09:00:56 INFO - Available Modes on Display 0 09:00:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:00:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:00:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:00:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:00:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:00:56 INFO - Intel Iris: 09:00:56 INFO - Chipset Model: Intel Iris 09:00:56 INFO - Type: GPU 09:00:56 INFO - Bus: Built-In 09:00:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:00:56 INFO - Vendor: Intel (0x8086) 09:00:56 INFO - Device ID: 0x0a2e 09:00:56 INFO - Revision ID: 0x0009 09:00:56 INFO - Displays: 09:00:56 INFO - Display: 09:00:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:00:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:00:56 INFO - Display Serial Number: 1600x1200 60 09:00:56 INFO - Main Display: Yes 09:00:56 INFO - Mirror: Off 09:00:56 INFO - Online: Yes 09:00:56 INFO - Rotation: Supported 09:00:56 INFO - Return code: 0 09:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:56 INFO - Python 2.7.3 09:00:56 INFO - Return code: 0 09:00:56 INFO - grabbing minidump binary from tooltool 09:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107046e00>, '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 0x106e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107041b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:56 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 09:00:56 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 09:00:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:56 INFO - Return code: 0 09:00:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:00:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:00:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:00:57 INFO - Return code: 0 09:00:57 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/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--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 09:00:57 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/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0065 --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 09:00:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kEt4FBCXnT/Render', 09:00:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:57 INFO - 'HOME': '/Users/cltbld', 09:00:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:57 INFO - 'LOGNAME': 'cltbld', 09:00:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:57 INFO - 'MOZ_NO_REMOTE': '1', 09:00:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:57 INFO - 'NO_EM_RESTART': '1', 09:00:57 INFO - 'PAGER': '/bin/cat', 09:00:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:57 INFO - 'PWD': '/builds/slave/test', 09:00:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:57 INFO - 'SHELL': '/bin/bash', 09:00:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l79qhjm7wI/Listeners', 09:00:57 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 09:00:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:57 INFO - 'USER': 'cltbld', 09:00:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:57 INFO - 'XPC_FLAGS': '0x0', 09:00:57 INFO - 'XPC_SERVICE_NAME': '0', 09:00:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:57 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/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--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 09:00:57 INFO - mozversion application_buildid: 20160418060925 09:00:57 INFO - mozversion application_changeset: 8975734f1546f7ab475037441e9a0591af299e32 09:00:57 INFO - mozversion application_display_name: Nightly 09:00:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:57 INFO - mozversion application_name: Firefox 09:00:57 INFO - mozversion application_remotingname: firefox 09:00:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:00:57 INFO - mozversion application_vendor: Mozilla 09:00:57 INFO - mozversion application_version: 48.0a1 09:00:57 INFO - mozversion platform_buildid: 20160418060925 09:00:57 INFO - mozversion platform_changeset: 8975734f1546f7ab475037441e9a0591af299e32 09:00:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:00:57 INFO - mozversion platform_version: 48.0a1 09:00:57 INFO - using testdate: 1460995257 09:00:57 INFO - actual date: 1460995257 09:00:57 INFO - starting webserver on 'localhost:56406' 09:00:57 INFO - SUITE-START | Running 2 tests 09:00:57 INFO - TEST-START | dromaeo_css 09:00:57 INFO - operating with platform_type : mac_ 09:00:57 INFO - Initialising browser for dromaeo_css test... 09:00:57 INFO - TEST-INFO | started process 6156 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpCJNPL3/profile http://localhost:56406/getInfo.html) 09:00:59 INFO - PROCESS | 6156 | __metrics Screen width/height:1600/1200 09:00:59 INFO - PROCESS | 6156 | colorDepth:24 09:00:59 INFO - PROCESS | 6156 | Browser inner width/height: 1024/689 09:00:59 INFO - PROCESS | 6156 | __metrics 09:01:00 INFO - PROCESS | 6156 | 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" 09:01:00 ERROR - PROCESS | 6156 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:01:00 INFO - TEST-INFO | 6156: exit 0 09:01:00 INFO - Browser initialized. 09:01:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:00 INFO - TEST-INFO | started process 6159 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpCJNPL3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:12 INFO - PROCESS | 6159 | RSS: Main: 237580288 09:01:12 INFO - PROCESS | 6159 | 09:02:04 INFO - PROCESS | 6159 | 1460995324014 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] 09:04:14 INFO - PROCESS | 6159 | Cycle 1(1): loaded http://localhost:56406/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56406/tests/dromaeo/cssquery-ext.html) 09:04:16 INFO - PROCESS | 6159 | RSS: Main: 343293952 09:04:16 INFO - PROCESS | 6159 | 09:07:19 INFO - PROCESS | 6159 | Cycle 1(1): loaded http://localhost:56406/tests/dromaeo/cssquery-ext.html (next: http://localhost:56406/tests/dromaeo/cssquery-jquery.html) 09:07:19 INFO - PROCESS | 6159 | RSS: Main: 373657600 09:07:19 INFO - PROCESS | 6159 | 09:10:21 INFO - PROCESS | 6159 | Cycle 1(1): loaded http://localhost:56406/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56406/tests/dromaeo/cssquery-mootools.html) 09:10:22 INFO - PROCESS | 6159 | RSS: Main: 375775232 09:10:22 INFO - PROCESS | 6159 | 09:13:24 INFO - PROCESS | 6159 | Cycle 1(1): loaded http://localhost:56406/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56406/tests/dromaeo/cssquery-prototype.html) 09:13:25 INFO - PROCESS | 6159 | RSS: Main: 377843712 09:13:25 INFO - PROCESS | 6159 | 09:16:28 INFO - PROCESS | 6159 | Cycle 1(1): loaded http://localhost:56406/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56406/tests/dromaeo/cssquery-yui.html) 09:16:29 INFO - PROCESS | 6159 | RSS: Main: 347549696 09:16:29 INFO - PROCESS | 6159 | 09:19:31 INFO - PROCESS | 6159 | Cycle 1(1): loaded http://localhost:56406/tests/dromaeo/cssquery-yui.html (next: http://localhost:56406/tests/dromaeo/cssquery-dojo.html) 09:19:32 INFO - PROCESS | 6159 | RSS: Main: 381829120 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - PROCESS | 6159 | __start_tp_report 09:19:32 INFO - PROCESS | 6159 | _x_x_mozilla_page_load 09:19:32 INFO - PROCESS | 6159 | _x_x_mozilla_page_load_details 09:19:32 INFO - PROCESS | 6159 | |i|pagename|runs| 09:19:32 INFO - PROCESS | 6159 | |0;dojo.html;3327.168207948013;3364.5794275715534;3385.8984230473093;3404.3361544498825;3426.2462258303162;4499.100179964008;5457.645253058558;5561;5608.046632072547;5678.063119585268;5621.409751976045;5622.128570071635;5725.4847063764355;5765.769369225236;5770.567207459437;5035.244713292998;5071.31537242473;5107.233914912756;5118.385793704742;5218.347706536683;5246.501582349671;5284.894302113956;5289.994900968823;5308.362996440414;5313.123334649778;5063.924041139386;5129.358830146231;5153.123968925292;5170.172772356443;5259.894802103956;5116.846494605168;5119.692818430869;5133.25567792668;5143.228515722634;5147.639665223436;5057.418396884369;5093.465186155438;5093.617978651579;5109.923351149736;5113.616478764107;5526.640768350045;5581.190727344512;5596.496315331599;5615.298087739033;5670.376258611549;6037.63774173551;6127.632342059446;6207.534434917399;6207.906881396782;6222.377762223787;6151.96924015377;6239.87520249597;6244.594101383441;6257.374262573751;6257.5619706620555;5778.913316300259;5787.739551720183;5801.825945221608;5807.041838096751;5811.186433899257;6162.537809664203;6169.475594574512;6256.249250090018;6307.495400368051;6308.968455157695;6004.969975150184;6075.605085669506;6079.787207447718;6089.299730530957;6102.389761023862;6192.102145188923;6199.256089269316;6200.596961197508;6214.8135555933395;6218.626882387071;6021.909671354933;6078.270607527125;6094.8943112006145;6139.7988032087915;6168.784092556739;6131.448169664752;6160.168377269011;6190.907136392868;6191.876162476725;6193.071039344134;4897.1674815281485;4913.11563918491;4922.286268491049;4927.088488629615;4965.503449655005;3579.8926032219074;4339;4454.532274111171;4481.775911204492;4534.569215924482;5638.323401191883;5675.5459563234845;5687.943120568824;5691.9715401423555;5696.3449203341315;6269;6277.058441233851;6278.246610406781;6291.276503202099;6299.055141728777;5528.723563821793;5555.055640541037;5611.7755289788765;5722.284714410699;5726.799562015392;5342.332208473941;5354.250404943311;5355.892882142335;5357;5358.571314294847;6249.281332646804;6293.748250070037;6307.495400368051;6333.303336632966;6344.302126766144;6128;6166;6189.319174890848;6292.2134733158355;6306.747730090745;6308.08532762756;6330.082138090043;6331.96834015827;6336.619802811846;6356;6141.232345956755;6149.72326245329;6158.630482171084;6188.195534580475;6190.226221722285;6132.049532322497;6144.955357589131;6215.160953271251;6223.751049957951;6243.594166379171;6072.848178795565;6073.089036644538;6088.1895072930565;6097.725602347817;6103.877922441615;6027.638341699512;6062.757489700362;6063.545234107548;6065.727042283196;6092.360302168208;5775.133729940542;5843.737031833492;5882.970585147047;5964.105384192406;5984.072468767348;6046.516278697605;6058.90911636317;6072.514198864169;6092.756289748538;6104.450599445893;5909.172715819703;5927.4665280125;5944.6730429826775;5963.075723262901;5992.43071908168 09:19:32 INFO - PROCESS | 6159 | |1;ext.html;486.48918635432807;503.9168537191364;506.92025984652685;527.180234734987;536.2438961064897;26091.608625870602;37164.44253336199;38439;38516;38572.228555428876;40264.19471610566;40814.59185408145;40943.566975155874;41007.38488922661;41024.58975410245;8395.328373730117;9404.81190376192;9634.47010414427;9638.37350572214;9642.614295428175;8394.034686011126;11433.256838305526;11781.23421977573;11795.646130616095;11847.111466639994;11399.772004559904;11537.826932596017;11550.53797848085;11558.248713833616;11609.361485118316;8422.831543369159;11285.379304138269;11293.54825806975;11456.5417383305;11464.770704585944;11338.376389298584;11482.253653512578;11497.5975840846;11504.424778761113;11518.827217591743;36217.63782362195;36708.26583468342;36813.711520096935;36836.71071512511;36873.44689829655;2883.8608749543814;3167.904962851118;3170.381775553768;3210.598675165604;3244.545763593099;27454.17637470879;28691.56962645562;28786.712132878612;28849.56725649117;28996.855015724996;2107.557412943284;2600.2979195617268;2601.8178727489085;2633.394319306551;2724.9318767030786;468512;469993;470475;471221;471475.99999999657;62645;62645;62997;63068.369316307166;63103;59012.70493647504;68817;68924.96612550816;68938.65530672314;68965;16896.746548801777;16990.915045424696;17074.060926648905;17096.57258568546;17131.400400986156;11719.355435450962;12062.758744825054;12622.873771262353;12939.158954667917;12967.740645187043;19339.90330048341;19340.8065919339;19445.90277048634;19461.610767784565;19467.318643847397;6547.9017814732815;6787.355201255873;8108.716194933149;8164.877526837102;8212.876806847902;946.9005754395689;997.1574019953129;1032.6798692405378;1077.6497638267595;1110.9611163609236;26641.733582664314;27157.864210679214;27409.45181096367;28827.991020314188;29642.85178574093;1088.6081010836092;1096.8957948994832;1120.4061847220985;1144.4106285263097;1221.6090850927617;14927.552173434815;15014.774778378332;15026.549203523911;15381.00023498469;15469.613259668598;598155;602609;606863;607979;608692;57711.71144144337;59777.40222597718;59883;60022.99999999913;60055.699721501114;1199.7840388730112;1283.4545318239711;1360.5034162530605;1417.3480199108526;1441.128117488946;28300.292492687026;28548.858045677942;28772.856135720023;28915.421691566473;29044.27389315284;44407;46436.53563464457;47201.527984719716;47285.763571181924;47288.52711472979;345863;347779.78330325574;348328;349018;350799;19218.61562768709;25993.610095848955;26656.333591659586;26826.731732682423;26983.785729642605;35044.29911401706;43887;45235.773821132;45357.77321113373;45662.31506527338;35214;36694;37262.813685931396;37501.81249093737;38227.42658860175;29740.70259297466;30574.471276436536;35102.64897350994;36372.363243654625;37374.06564835901;31457.898973535815;35609.46585801267;36762.63237367699;37058.44412333871;37108.44337334996;26953.86523067372;30581.54127688042;30661.54007689842;30875.140607424073;32162.51756223705;21613.567728645652;24181.758182418653;24343.713598663955;24779.380515487257;25012.62481062822 09:19:32 INFO - PROCESS | 6159 | |2;jquery.html;396.6133020305203;398.5158032989918;400.5793916387793;408.9795510224488;413.4831460674157;19228.423147305602;19269.518262043457;20027.399178024647;20039.799602003975;20424;16676.33294668215;19626.92051937143;19713.901430492828;20617.484562885937;20631;5699.344575373843;5839.591228613998;6180.783672571461;6180.907286390685;6181.6600086995195;5048.545630893219;5864.4135586441225;6152.384761523835;6161.938380616181;6163.291221509517;6078.027515597505;6079.78720744774;6101.237345331833;6112.297085835142;6128.754849806002;5499;5887.8528036799;6088.360722124202;6089.817305480843;6090.908636370458;5468.070428027245;5660.971695141539;5661.320641523002;5675.602707810455;5681.0910254359105;13152.802707959292;13357.532486363029;13390.464381424732;13407.798883016763;13447.731045379038;2047;2091.8117369436673;2096.716943212662;2239.3393948785147;2240.439890027493;9293.488858112802;9952.203823694159;9956.950215248877;10085.495725213783;10110.898891011142;1685.1153144599184;1712.9743053854202;1749.2653085704035;1765.7181335262067;1778.7776527934009;88088.97475910913;99396.00603994013;117925;120581.79418205882;123246.76753232532;11363.090952723762;11536.192466527431;11651.951324380847;11690.766184676258;11900.523979040914;13116.147450415692;13634.250116243702;13659.248741319125;15017.474388396353;15056.548303550911;7575.583342916082;7599.734009309647;8339.791505212419;8512.787180320418;8633.568321583896;8294.419390642717;8298.502089874626;8320.500769953702;8390.622421991025;8435.95782021086;4408.096340250241;4488.820447182142;4508.48199192575;4508.616767574727;4574.4968053514085;1911.3405874973112;1946.7469229000424;1952.681939690703;1957.3247229705728;1988.413418041681;924.990750092504;983.6508039645965;987.0603027512876;998.8152191844532;1014.9441780702132;18825.623487530174;19180.904095479713;19601.509962250773;19836.10737516815;20157.19371225164;817.8854960305562;825.7729124490813;951.7668171297938;994.080475560104;995.4425521707867;8454.661813527513;9801.50992450389;10380.480975951172;10388;10402.375857448576;576733;599373;602108;603649;608984;118992.40503797599;122986;123973.38013310057;124913;125613.37193313974;1020.5560581147224;1021.933574317667;1046.3303485769113;1047.2773786087575;1049.6955882793904;14139.929300353433;17232.827671723124;17493.212805423533;17530.211140498453;17534.73697894558;48199.75900120617;48989.755051224514;49354;50360.985560577166;51911.74044129755;74025.6298718503;75232.99068037217;76648;79014.41971160659;80374.598127009;11137.276077054965;11628.94185529067;11806;12030.398480076135;12143.574974875837;7120.501564890616;12366.712158250752;12373.13388062827;12642.557510487457;12740.989103101485;11072.833907491233;11388.886111138783;11404.543818247177;11446.656600302003;11532.423378831192;10947.945260273647;11040.834387484034;11171.218014739216;11408.201425900108;11466.484008219817;11145.72135696614;11329.603463878724;11658.300501969909;11700.824487632863;11748.58879939198;11810.05519558416;11828.46771895284;11993.201019847093;12007.699807504881;12073.939630301793;11076.612318569134;11168.385738784445;11477.426128693365;11500.884991149982;11516.30902145874 09:19:32 INFO - PROCESS | 6159 | |3;mootools.html;1414.7170565886825;1580.0677600215874;1600.359856057577;1813.2112531048995;1960.8725432846863;7885.763427097189;8638.740837774869;8843.425177363475;8846.336524760636;8857.689980850671;8396.706115285966;8648.264897483707;8666.91333086669;8671.653133874637;8682.565871706422;2111.1766411099684;2376.7979721723673;2404.2186289455954;2433.245693834917;2456.361346050022;2433.7444568320248;2457.1645640482234;2462.175171317611;2462.495188486356;2465.272744540365;2387.7134743830766;2409.5421869844763;2417.2385698505004;2417.4802417480237;2425.320910145162;2115.693224482457;2333.5916214662366;2409.7590240975937;2423.6849209602815;2438.829281950264;2416.6012607919674;2444.8655323957173;2451.2768733223743;2453.0065323543995;2453.1659235859715;8473.661053557851;8535.615897284575;8539.35954803386;8560.571971401467;8565.95717021411;5101.4133374662015;5332.440093790135;5335.573154147659;5379.1662292344745;5385.165299378603;3486.9128271793156;3525.2420729543273;3581.8209089545417;3584.7490675652716;3599.2801439711902;1014.4471263161521;1034.1881622926041;1035.0787798858946;1041.1254546181244;1046.1055797293282;10980;11159.888401115966;11164.609238676689;11169.776604467865;11180.937810908032;4533.252013417749;4602.194615942197;4821.614270858323;4826.758662066883;4840.2255639098275;9823.852642210373;9824.214062875095;9830.705078847646;9835.065668761456;9840.950795245977;5297.099493086184;5303.36359636846;5320.920186197211;5360.088784906575;5361.865953351119;3977.92044159121;4356.0198955235455;4496.707713998645;4507.549245075466;4875.19559272716;4057.127717540743;4112.424260603518;4126.401671757579;4181.60274773896;4220.430241917302;1179.4397661111075;1265.949362025527;1296.0797833538293;1312.527490103562;1319.874611911886;843.9324854011664;878.1701292278735;889.155302462663;893.6425429828121;908.3513423136985;5633.63381380209;5787.565932555075;5803.564732645069;5808.419158084243;5940.376260492672;897.5646811296516;909.949952752605;930.1767935377104;935.4200395754675;994.1897353656747;3767.7362584619364;3835.1179228777537;3871.709621778378;3913.96086039136;3929.842806287774;10100.292979491513;10427.895721042843;10591.735206619896;10603.84094238587;10632.89367106319;10581.57673693059;10664.573417063386;10679.359238445719;10736.838947415796;10951.069159121405;7015.912533557307;7950.761477155695;8046.396520261003;8200.958995204986;8214.917850821535;5627.465390787868;5629.408912064338;5869.970650146722;5890.852728681817;5919.830633725432;8398.83202335962;9161.541922903962;9313.767155821159;9701.466419347003;9738.951305243429;9122.361434699773;9564.187044101329;9836.311458198144;9968.40189588627;10114.747131321776;6886.173659160932;7270.127584689871;7503.9249607503225;7515.774526764206;7526.134494533198;8018.83962320762;8032.357411407191;8062.992125984252;8084.555349455602;8130.715424960098;7134.750283740044;7192.964035179791;7210.423166146591;7332.046833911771;7346.889796652948;6889.276625954403;7043.401310888632;7086.007958885657;7151.356377926011;7231.783046508614;6735.393814556517;6828.692708828015;6831.795046148623;6837.418819400406;6843.486738494533;4624.606908412688;4770.737609431376;4895.363602731622;4910.140725373117;4918.991606720542;4232.724872883253;4835.274708793709;4895.290182923385;4934.481879402611;4962.156433406401 09:19:32 INFO - PROCESS | 6159 | |4;prototype.html;316.61531239543956;318.6568613294961;322.70311313591503;327.6837851473328;331.03833364077366;5142.228665700147;5681.460261275178;5914.733836977335;5960.314563825162;5985.5810093293485;5851.297844258695;5855.560832937525;5889.322727886294;5901.114832775086;5903.49820265278;3685.5761587417524;4021.0349516116066;4150.2647617144585;4451.1765323415275;4505.526919673436;3976;4062.5531191568903;4423.513413524526;4432.268675668511;4513.593776560109;3866.99458140884;4288.571142885702;4427.645788336941;4432.002799370148;4434.933475997863;3893.046203680088;4101.302778527627;4149.7302675326155;4200.810963506649;4421.137878113769;3489;3809.066778639251;3813.427985802124;3827.598102199257;3829.885103446873;4942.505749425065;5269.420363759983;5480.808171713971;5616.157576363578;5718.59969802114;4878.146324393217;5084.211947148198;5112.488751124858;5155.40712818027;5163.561097306733;6128.0195168773225;6178.474829639486;6201.844953876144;6224.576482934368;6248.250209974832;3568.553930758655;3731.2910546996254;4278.465191851019;4293.377460268243;4294.141171765659;6193.009118541012;6519.576227545194;6582.901256481156;6584.967075164594;6589.769358072444;3563.501109844624;4317.877351888532;4330.7401555906745;4337.175936572105;4341.696081274344;4406.9559304407185;4409.3606427068535;4410.2282100632265;4416.271315232951;4419.779011049499;4779.187538118528;4998.375203099613;5006.374203224597;5017;5043.3191519145175;6278.717457714414;6318.747250109944;6324.747010119544;6340.809775706736;6359.8092057238355;1211.8969887559656;1437.4322142753554;1467.8972471927077;1479.8964072515037;1479.9260036998107;897.1611543207122;926.4898215908026;957.8084383123403;968.4673429613684;972.2465089555651;5170.922436163461;5466.890662186734;5803.111915016864;5972.850678733066;5984.401559843981;6579.17760279965;6587.736490540325;6592.604443733391;6613.933860661428;6614.470842332602;5663.725661726078;5977.880442391128;5982.521398288127;6006.909896351558;6021.036634138517;2888.812227205225;2995.1613548206537;3170.906037417087;3347.9665203347654;3459.7578169528397;5190.429052804188;5587.44125587438;6527.412532872065;6575.178102737158;6761.763338283136;6619.437347825488;6654.70053847578;6688.197416310074;6688.899666505006;6710.899336509956;6161.568690191733;6356.841078973062;6552.180977377828;6774.3225677431865;6797;6484;6486.189226346707;6507.219133703797;6523.380278873499;6529.444997175104;6390.201224846895;6404.391582799627;6407.10300557913;6418.550701450854;6438.323975982455;6435.678216089271;6729.822281100885;6774.390304872585;6778.4916131289365;6803.965980170067;3775.6979441644053;3986.920261594694;4159.292920203512;4243.809028593782;4248.851290204828;6205.5345849060595;6512.544121911421;6525.869482610226;6545.181852268466;6550;6296.937030629819;6335.4931605472375;6343.5876668016435;6352.140612431402;6363.331850155852;6264.874702506016;6302.991521356561;6316.873662526816;6336.3030066693545;6338.936610633835;5353.437889021597;5810.593258472036;5869.331667707683;6104.17593624855;6248.656323902229;5527.668339899458;6121.632702037892;6180.752769889334;6217.253929528486;6293.276273228637;5130.743462826769;5218.504242096995;5764.913526297025;6028.216331876828;6048.334683184758 09:19:32 INFO - PROCESS | 6159 | |5;yui.html;659.1628631637819;719.96040217788;751.8270797716528;761.0638914135611;792.4848848248641;14435.71128577429;14878.032927859682;14885.25573721315;14898.553043408701;14902.627434314147;11811.40942952851;13685.178889266626;13758.10572312799;13762.105463144888;13762.174269543835;732.655651843634;909.4815954905706;934.4019827310527;940.1585580905086;943.3113826906363;897.9506127163004;907.4555266839876;909.381620498061;909.5634095634067;912.598456679059;960.7742180587557;964.961401543941;984.7193549838297;985.7387792235063;986.7385142937128;959.8512230604267;971.2618410008374;974.3666616699167;975.11264749078;977.5112443778111;550.7851937744283;569.336722718033;569.9629524080922;570.2415787003275;729.6534146280527;13298.933505332509;13701.314934253247;13729.656758581115;13733.313334333343;13744.86255137446;12191.695207619792;12222.572209972697;12230.938845305805;12405.813912791315;12562.68593285166;9412.482313472825;9570.760730981712;9594.520273986273;9620.903790962071;9907.405555666683;3438.707709844641;3497.335506253829;3497.387957107496;3516.771409858351;3596.1728802375337;79941.60029199875;81455.9999999994;82492.58753706193;82671;82721;8559.186877246651;8813.33899957493;9077.364584479155;9308.069193080637;9553.331266811256;19882.204711811653;19899.418104740995;19914.800851991295;19938.20247190096;20318.593628127353;7214.74748383804;7223.783286501413;7232.0959880015;7255.383292420098;7292.598907060056;3364.9327013459592;3835.53973523179;3962.445257663929;3970.146418520032;4008.7795171265275;3771.698264138862;3999.8400063997115;4002.899202719217;4008.6392224699916;4010.3583426651594;679.1917618034523;690.1717938473752;699.3076853914588;713.2439614009166;715.4598278299954;509;538.2330179494205;572.2732130194629;577.2841676321332;592.3454582686156;6816.693248803816;7428.4428667850125;7692.7692169235015;7866.331361834194;7932.325752311002;549.7251374312843;567.5658121537027;582.5718097198555;583.6177303865975;592.1827877529068;2851.8877637721307;3504.684578387957;3672.265546890633;3860.575336712959;3916.5300163979937;98926;99552.50223748836;100675.49662251642;103765;103926.7643941366;39641;39662.801685991384;39911.60088399079;40834.774956751346;55662;74567.50864982778;75071;75787;77476.61261693656;77489;7394.7781566553085;7491;7562.773116806505;7570.735024274124;7575.659095340833;45582;46053.76973115113;46059.53940460687;46383.76808115938;46525.767371162925;61400.07899881416;61612.38387616067;68299;70036.08304097067;71889.56220875657;9770.706878793648;11178.608748694081;11471.885281147415;11718.824217636913;12365.443555039865;11850.703732406759;11980.442542469424;12083.818742718691;12331.568395106127;12390.938045309716;10185.490725463846;11034.944825275823;11179.385133817394;11432;11737.061035117;8923.959404347674;10844.511996960313;11456.427178641201;11674.533018679158;12579;11024.118070554176;11164.385958772345;11225.270357426742;11527.596534121602;11769.293842369485;9261;9368.016358282282;9382.20815401359;9541.570629321559;9794.363366381447;9373.765655858659;9423.29325300619;9432.245420366491;9473.289503287418;9485.525723713648 09:19:32 INFO - PROCESS | 6159 | __end_tp_report 09:19:32 INFO - PROCESS | 6159 | __start_cc_report 09:19:32 INFO - PROCESS | 6159 | _x_x_mozilla_cycle_collect,1117 09:19:32 INFO - PROCESS | 6159 | __end_cc_report 09:19:32 INFO - PROCESS | 6159 | __startTimestamp1460996372153__endTimestamp 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - PROCESS | 6159 | ------- Summary: start ------- 09:19:32 INFO - PROCESS | 6159 | Number of tests: 6 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - PROCESS | 6159 | [#0] dojo.html Cycles:165 Average:5703.66 Median:5964.11 stddev:654.47 (11.0%) stddev-sans-first:629.36 09:19:32 INFO - PROCESS | 6159 | Values: 3327.2 3364.6 3385.9 3404.3 3426.2 4499.1 5457.6 5561.0 5608.0 5678.1 5621.4 5622.1 5725.5 5765.8 5770.6 5035.2 5071.3 5107.2 5118.4 5218.3 5246.5 5284.9 5290.0 5308.4 5313.1 5063.9 5129.4 5153.1 5170.2 5259.9 5116.8 5119.7 5133.3 5143.2 5147.6 5057.4 5093.5 5093.6 5109.9 5113.6 5526.6 5581.2 5596.5 5615.3 5670.4 6037.6 6127.6 6207.5 6207.9 6222.4 6152.0 6239.9 6244.6 6257.4 6257.6 5778.9 5787.7 5801.8 5807.0 5811.2 6162.5 6169.5 6256.2 6307.5 6309.0 6005.0 6075.6 6079.8 6089.3 6102.4 6192.1 6199.3 6200.6 6214.8 6218.6 6021.9 6078.3 6094.9 6139.8 6168.8 6131.4 6160.2 6190.9 6191.9 6193.1 4897.2 4913.1 4922.3 4927.1 4965.5 3579.9 4339.0 4454.5 4481.8 4534.6 5638.3 5675.5 5687.9 5692.0 5696.3 6269.0 6277.1 6278.2 6291.3 6299.1 5528.7 5555.1 5611.8 5722.3 5726.8 5342.3 5354.3 5355.9 5357.0 5358.6 6249.3 6293.7 6307.5 6333.3 6344.3 6128.0 6166.0 6189.3 6292.2 6306.7 6308.1 6330.1 6332.0 6336.6 6356.0 6141.2 6149.7 6158.6 6188.2 6190.2 6132.0 6145.0 6215.2 6223.8 6243.6 6072.8 6073.1 6088.2 6097.7 6103.9 6027.6 6062.8 6063.5 6065.7 6092.4 5775.1 5843.7 5883.0 5964.1 5984.1 6046.5 6058.9 6072.5 6092.8 6104.5 5909.2 5927.5 5944.7 5963.1 5992.4 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - PROCESS | 6159 | [#1] ext.html Cycles:180 Average:61727.23 Median:3169.14 stddev:129641.74 (4090.8%) stddev-sans-first:129923.88 09:19:32 INFO - PROCESS | 6159 | Values: 486.5 503.9 506.9 527.2 536.2 26091.6 37164.4 38439.0 38516.0 38572.2 40264.2 40814.6 40943.6 41007.4 41024.6 8395.3 9404.8 9634.5 9638.4 9642.6 8394.0 11433.3 11781.2 11795.6 11847.1 11399.8 11537.8 11550.5 11558.2 11609.4 8422.8 11285.4 11293.5 11456.5 11464.8 11338.4 11482.3 11497.6 11504.4 11518.8 36217.6 36708.3 36813.7 36836.7 36873.4 2883.9 3167.9 3170.4 3210.6 3244.5 27454.2 28691.6 28786.7 28849.6 28996.9 2107.6 2600.3 2601.8 2633.4 2724.9 468512.0 469993.0 470475.0 471221.0 471476.0 62645.0 62645.0 62997.0 63068.4 63103.0 59012.7 68817.0 68925.0 68938.7 68965.0 16896.7 16990.9 17074.1 17096.6 17131.4 11719.4 12062.8 12622.9 12939.2 12967.7 19339.9 19340.8 19445.9 19461.6 19467.3 6547.9 6787.4 8108.7 8164.9 8212.9 946.9 997.2 1032.7 1077.6 1111.0 26641.7 27157.9 27409.5 28828.0 29642.9 1088.6 1096.9 1120.4 1144.4 1221.6 14927.6 15014.8 15026.5 15381.0 15469.6 598155.0 602609.0 606863.0 607979.0 608692.0 57711.7 59777.4 59883.0 60023.0 60055.7 1199.8 1283.5 1360.5 1417.3 1441.1 28300.3 28548.9 28772.9 28915.4 29044.3 44407.0 46436.5 47201.5 47285.8 47288.5 345863.0 347779.8 348328.0 349018.0 350799.0 19218.6 25993.6 26656.3 26826.7 26983.8 35044.3 43887.0 45235.8 45357.8 45662.3 35214.0 36694.0 37262.8 37501.8 38227.4 29740.7 30574.5 35102.6 36372.4 37374.1 31457.9 35609.5 36762.6 37058.4 37108.4 26953.9 30581.5 30661.5 30875.1 32162.5 21613.6 24181.8 24343.7 24779.4 25012.6 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - PROCESS | 6159 | [#2] jquery.html Cycles:180 Average:34245.03 Median:10912.26 stddev:99583.28 (912.6%) stddev-sans-first:99830.20 09:19:32 INFO - PROCESS | 6159 | Values: 396.6 398.5 400.6 409.0 413.5 19228.4 19269.5 20027.4 20039.8 20424.0 16676.3 19626.9 19713.9 20617.5 20631.0 5699.3 5839.6 6180.8 6180.9 6181.7 5048.5 5864.4 6152.4 6161.9 6163.3 6078.0 6079.8 6101.2 6112.3 6128.8 5499.0 5887.9 6088.4 6089.8 6090.9 5468.1 5661.0 5661.3 5675.6 5681.1 13152.8 13357.5 13390.5 13407.8 13447.7 2047.0 2091.8 2096.7 2239.3 2240.4 9293.5 9952.2 9957.0 10085.5 10110.9 1685.1 1713.0 1749.3 1765.7 1778.8 88089.0 99396.0 117925.0 120581.8 123246.8 11363.1 11536.2 11652.0 11690.8 11900.5 13116.1 13634.3 13659.2 15017.5 15056.5 7575.6 7599.7 8339.8 8512.8 8633.6 8294.4 8298.5 8320.5 8390.6 8436.0 4408.1 4488.8 4508.5 4508.6 4574.5 1911.3 1946.7 1952.7 1957.3 1988.4 925.0 983.7 987.1 998.8 1014.9 18825.6 19180.9 19601.5 19836.1 20157.2 817.9 825.8 951.8 994.1 995.4 8454.7 9801.5 10380.5 10388.0 10402.4 576733.0 599373.0 602108.0 603649.0 608984.0 118992.4 122986.0 123973.4 124913.0 125613.4 1020.6 1021.9 1046.3 1047.3 1049.7 14139.9 17232.8 17493.2 17530.2 17534.7 48199.8 48989.8 49354.0 50361.0 51911.7 74025.6 75233.0 76648.0 79014.4 80374.6 11137.3 11628.9 11806.0 12030.4 12143.6 7120.5 12366.7 12373.1 12642.6 12741.0 11072.8 11388.9 11404.5 11446.7 11532.4 10947.9 11040.8 11171.2 11408.2 11466.5 11145.7 11329.6 11658.3 11700.8 11748.6 11810.1 11828.5 11993.2 12007.7 12073.9 11076.6 11168.4 11477.4 11500.9 11516.3 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - PROCESS | 6159 | [#3] mootools.html Cycles:180 Average:5602.36 Median:5031.78 stddev:3084.45 (61.3%) stddev-sans-first:3077.05 09:19:32 INFO - PROCESS | 6159 | Values: 1414.7 1580.1 1600.4 1813.2 1960.9 7885.8 8638.7 8843.4 8846.3 8857.7 8396.7 8648.3 8666.9 8671.7 8682.6 2111.2 2376.8 2404.2 2433.2 2456.4 2433.7 2457.2 2462.2 2462.5 2465.3 2387.7 2409.5 2417.2 2417.5 2425.3 2115.7 2333.6 2409.8 2423.7 2438.8 2416.6 2444.9 2451.3 2453.0 2453.2 8473.7 8535.6 8539.4 8560.6 8566.0 5101.4 5332.4 5335.6 5379.2 5385.2 3486.9 3525.2 3581.8 3584.7 3599.3 1014.4 1034.2 1035.1 1041.1 1046.1 10980.0 11159.9 11164.6 11169.8 11180.9 4533.3 4602.2 4821.6 4826.8 4840.2 9823.9 9824.2 9830.7 9835.1 9841.0 5297.1 5303.4 5320.9 5360.1 5361.9 3977.9 4356.0 4496.7 4507.5 4875.2 4057.1 4112.4 4126.4 4181.6 4220.4 1179.4 1265.9 1296.1 1312.5 1319.9 843.9 878.2 889.2 893.6 908.4 5633.6 5787.6 5803.6 5808.4 5940.4 897.6 909.9 930.2 935.4 994.2 3767.7 3835.1 3871.7 3914.0 3929.8 10100.3 10427.9 10591.7 10603.8 10632.9 10581.6 10664.6 10679.4 10736.8 10951.1 7015.9 7950.8 8046.4 8201.0 8214.9 5627.5 5629.4 5870.0 5890.9 5919.8 8398.8 9161.5 9313.8 9701.5 9739.0 9122.4 9564.2 9836.3 9968.4 10114.7 6886.2 7270.1 7503.9 7515.8 7526.1 8018.8 8032.4 8063.0 8084.6 8130.7 7134.8 7193.0 7210.4 7332.0 7346.9 6889.3 7043.4 7086.0 7151.4 7231.8 6735.4 6828.7 6831.8 6837.4 6843.5 4624.6 4770.7 4895.4 4910.1 4919.0 4232.7 4835.3 4895.3 4934.5 4962.2 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - PROCESS | 6159 | [#4] prototype.html Cycles:180 Average:5087.49 Median:5862.45 stddev:1629.24 (27.8%) stddev-sans-first:1593.97 09:19:32 INFO - PROCESS | 6159 | Values: 316.6 318.7 322.7 327.7 331.0 5142.2 5681.5 5914.7 5960.3 5985.6 5851.3 5855.6 5889.3 5901.1 5903.5 3685.6 4021.0 4150.3 4451.2 4505.5 3976.0 4062.6 4423.5 4432.3 4513.6 3867.0 4288.6 4427.6 4432.0 4434.9 3893.0 4101.3 4149.7 4200.8 4421.1 3489.0 3809.1 3813.4 3827.6 3829.9 4942.5 5269.4 5480.8 5616.2 5718.6 4878.1 5084.2 5112.5 5155.4 5163.6 6128.0 6178.5 6201.8 6224.6 6248.3 3568.6 3731.3 4278.5 4293.4 4294.1 6193.0 6519.6 6582.9 6585.0 6589.8 3563.5 4317.9 4330.7 4337.2 4341.7 4407.0 4409.4 4410.2 4416.3 4419.8 4779.2 4998.4 5006.4 5017.0 5043.3 6278.7 6318.7 6324.7 6340.8 6359.8 1211.9 1437.4 1467.9 1479.9 1479.9 897.2 926.5 957.8 968.5 972.2 5170.9 5466.9 5803.1 5972.9 5984.4 6579.2 6587.7 6592.6 6613.9 6614.5 5663.7 5977.9 5982.5 6006.9 6021.0 2888.8 2995.2 3170.9 3348.0 3459.8 5190.4 5587.4 6527.4 6575.2 6761.8 6619.4 6654.7 6688.2 6688.9 6710.9 6161.6 6356.8 6552.2 6774.3 6797.0 6484.0 6486.2 6507.2 6523.4 6529.4 6390.2 6404.4 6407.1 6418.6 6438.3 6435.7 6729.8 6774.4 6778.5 6804.0 3775.7 3986.9 4159.3 4243.8 4248.9 6205.5 6512.5 6525.9 6545.2 6550.0 6296.9 6335.5 6343.6 6352.1 6363.3 6264.9 6303.0 6316.9 6336.3 6338.9 5353.4 5810.6 5869.3 6104.2 6248.7 5527.7 6121.6 6180.8 6217.3 6293.3 5130.7 5218.5 5764.9 6028.2 6048.3 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - PROCESS | 6159 | [#5] yui.html Cycles:180 Average:17443.85 Median:579.93 stddev:25166.84 (4339.6%) stddev-sans-first:25205.88 09:19:32 INFO - PROCESS | 6159 | Values: 659.2 720.0 751.8 761.1 792.5 14435.7 14878.0 14885.3 14898.6 14902.6 11811.4 13685.2 13758.1 13762.1 13762.2 732.7 909.5 934.4 940.2 943.3 898.0 907.5 909.4 909.6 912.6 960.8 965.0 984.7 985.7 986.7 959.9 971.3 974.4 975.1 977.5 550.8 569.3 570.0 570.2 729.7 13298.9 13701.3 13729.7 13733.3 13744.9 12191.7 12222.6 12230.9 12405.8 12562.7 9412.5 9570.8 9594.5 9620.9 9907.4 3438.7 3497.3 3497.4 3516.8 3596.2 79941.6 81456.0 82492.6 82671.0 82721.0 8559.2 8813.3 9077.4 9308.1 9553.3 19882.2 19899.4 19914.8 19938.2 20318.6 7214.7 7223.8 7232.1 7255.4 7292.6 3364.9 3835.5 3962.4 3970.1 4008.8 3771.7 3999.8 4002.9 4008.6 4010.4 679.2 690.2 699.3 713.2 715.5 509.0 538.2 572.3 577.3 592.3 6816.7 7428.4 7692.8 7866.3 7932.3 549.7 567.6 582.6 583.6 592.2 2851.9 3504.7 3672.3 3860.6 3916.5 98926.0 99552.5 100675.5 103765.0 103926.8 39641.0 39662.8 39911.6 40834.8 55662.0 74567.5 75071.0 75787.0 77476.6 77489.0 7394.8 7491.0 7562.8 7570.7 7575.7 45582.0 46053.8 46059.5 46383.8 46525.8 61400.1 61612.4 68299.0 70036.1 71889.6 9770.7 11178.6 11471.9 11718.8 12365.4 11850.7 11980.4 12083.8 12331.6 12390.9 10185.5 11034.9 11179.4 11432.0 11737.1 8924.0 10844.5 11456.4 11674.5 12579.0 11024.1 11164.4 11225.3 11527.6 11769.3 9261.0 9368.0 9382.2 9541.6 9794.4 9373.8 9423.3 9432.2 9473.3 9485.5 09:19:32 INFO - PROCESS | 6159 | -------- Summary: end -------- 09:19:32 INFO - PROCESS | 6159 | 09:19:32 INFO - TEST-INFO | 6159: exit 0 09:19:32 INFO - TEST-OK | dromaeo_css | took 1114898ms 09:19:32 INFO - TEST-START | kraken 09:19:32 INFO - operating with platform_type : mac_ 09:19:32 INFO - Initialising browser for kraken test... 09:19:32 INFO - TEST-INFO | started process 6163 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmp79gul7/profile http://localhost:56406/getInfo.html) 09:19:34 INFO - PROCESS | 6163 | __metrics Screen width/height:1600/1200 09:19:34 INFO - PROCESS | 6163 | colorDepth:24 09:19:34 INFO - PROCESS | 6163 | Browser inner width/height: 1024/689 09:19:34 INFO - PROCESS | 6163 | __metrics 09:19:34 INFO - PROCESS | 6163 | 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" 09:19:34 ERROR - PROCESS | 6163 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:19:35 INFO - TEST-INFO | 6163: exit 0 09:19:35 INFO - Browser initialized. 09:19:35 INFO - Running cycle 1/1 for kraken test... 09:19:35 INFO - TEST-INFO | started process 6165 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmp79gul7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:47 INFO - PROCESS | 6165 | RSS: Main: 237314048 09:19:47 INFO - PROCESS | 6165 | 09:19:50 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56406/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:51 INFO - PROCESS | 6165 | RSS: Main: 285466624 09:19:51 INFO - PROCESS | 6165 | 09:19:53 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56406/tests/kraken/driver.html?testName=audio-dft) 09:19:54 INFO - PROCESS | 6165 | RSS: Main: 298786816 09:19:54 INFO - PROCESS | 6165 | 09:19:57 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56406/tests/kraken/driver.html?testName=audio-fft) 09:19:58 INFO - PROCESS | 6165 | RSS: Main: 384708608 09:19:58 INFO - PROCESS | 6165 | 09:20:00 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56406/tests/kraken/driver.html?testName=audio-oscillator) 09:20:01 INFO - PROCESS | 6165 | RSS: Main: 284168192 09:20:01 INFO - PROCESS | 6165 | 09:20:03 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56406/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:03 INFO - PROCESS | 6165 | RSS: Main: 289873920 09:20:03 INFO - PROCESS | 6165 | 09:20:06 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56406/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:07 INFO - PROCESS | 6165 | RSS: Main: 325611520 09:20:07 INFO - PROCESS | 6165 | 09:20:09 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56406/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:10 INFO - PROCESS | 6165 | RSS: Main: 328085504 09:20:10 INFO - PROCESS | 6165 | 09:20:13 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56406/tests/kraken/driver.html?testName=json-parse-financial) 09:20:14 INFO - PROCESS | 6165 | RSS: Main: 325029888 09:20:14 INFO - PROCESS | 6165 | 09:20:15 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56406/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:16 INFO - PROCESS | 6165 | RSS: Main: 319242240 09:20:16 INFO - PROCESS | 6165 | 09:20:18 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56406/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:18 INFO - PROCESS | 6165 | RSS: Main: 326189056 09:20:18 INFO - PROCESS | 6165 | 09:20:20 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56406/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:21 INFO - PROCESS | 6165 | RSS: Main: 329388032 09:20:21 INFO - PROCESS | 6165 | 09:20:24 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56406/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:25 INFO - PROCESS | 6165 | RSS: Main: 331190272 09:20:25 INFO - PROCESS | 6165 | 09:20:28 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56406/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:28 INFO - PROCESS | 6165 | RSS: Main: 327008256 09:20:28 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | Cycle 1(1): loaded http://localhost:56406/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56406/tests/kraken/driver.html?testName=ai-astar) 09:20:30 INFO - PROCESS | 6165 | RSS: Main: 390664192 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | __start_tp_report 09:20:30 INFO - PROCESS | 6165 | _x_x_mozilla_page_load 09:20:30 INFO - PROCESS | 6165 | _x_x_mozilla_page_load_details 09:20:30 INFO - PROCESS | 6165 | |i|pagename|runs| 09:20:30 INFO - PROCESS | 6165 | |0;ai-astar;111;111;113;112;111;113;118;111;111;113 09:20:30 INFO - PROCESS | 6165 | |1;audio-beat-detection;159;120;146;175;121;145;163;147;186;120 09:20:30 INFO - PROCESS | 6165 | |2;audio-dft;185;186;188;184;185;184;181;186;186;184 09:20:30 INFO - PROCESS | 6165 | |3;audio-fft;76;72;89;92;57;59;57;79;86;56 09:20:30 INFO - PROCESS | 6165 | |4;audio-oscillator;92;98;138;78;78;76;127;80;78;127 09:20:30 INFO - PROCESS | 6165 | |5;imaging-gaussian-blur;108;85;87;85;120;84;88;85;87;85 09:20:30 INFO - PROCESS | 6165 | |6;imaging-darkroom;93;94;93;94;92;92;93;93;93;93 09:20:30 INFO - PROCESS | 6165 | |7;imaging-desaturate;84;107;114;143;141;85;86;85;86;86 09:20:30 INFO - PROCESS | 6165 | |8;json-parse-financial;59;60;56;57;57;59;58;74;58;56 09:20:30 INFO - PROCESS | 6165 | |9;json-stringify-tinderbox;64;73;62;77;71;68;67;69;69;68 09:20:30 INFO - PROCESS | 6165 | |10;stanford-crypto-aes;81;81;82;82;81;82;81;82;81;83 09:20:30 INFO - PROCESS | 6165 | |11;stanford-crypto-ccm;138;138;136;136;138;138;136;137;231;141 09:20:30 INFO - PROCESS | 6165 | |12;stanford-crypto-pbkdf2;179;170;171;169;170;170;169;170;167;175 09:20:30 INFO - PROCESS | 6165 | |13;stanford-crypto-sha256-iterative;70;68;68;68;68;68;67;71;68;68 09:20:30 INFO - PROCESS | 6165 | __end_tp_report 09:20:30 INFO - PROCESS | 6165 | __start_cc_report 09:20:30 INFO - PROCESS | 6165 | _x_x_mozilla_cycle_collect,1961 09:20:30 INFO - PROCESS | 6165 | __end_cc_report 09:20:30 INFO - PROCESS | 6165 | __startTimestamp1460996430606__endTimestamp 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | ------- Summary: start ------- 09:20:30 INFO - PROCESS | 6165 | Number of tests: 14 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.50 stddev:2.17 (1.9%) stddev-sans-first:2.24 09:20:30 INFO - PROCESS | 6165 | Values: 111.0 111.0 113.0 112.0 111.0 113.0 118.0 111.0 111.0 113.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:153.00 stddev:23.17 (15.1%) stddev-sans-first:24.24 09:20:30 INFO - PROCESS | 6165 | Values: 159.0 120.0 146.0 175.0 121.0 145.0 163.0 147.0 186.0 120.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:1.85 (1.0%) stddev-sans-first:1.96 09:20:30 INFO - PROCESS | 6165 | Values: 185.0 186.0 188.0 184.0 185.0 184.0 181.0 186.0 186.0 184.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#3] audio-fft Cycles:10 Average:72.30 Median:77.50 stddev:14.24 (18.4%) stddev-sans-first:15.04 09:20:30 INFO - PROCESS | 6165 | Values: 76.0 72.0 89.0 92.0 57.0 59.0 57.0 79.0 86.0 56.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:78.00 stddev:24.31 (31.2%) stddev-sans-first:25.71 09:20:30 INFO - PROCESS | 6165 | Values: 92.0 98.0 138.0 78.0 78.0 76.0 127.0 80.0 78.0 127.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:85.00 stddev:12.30 (14.5%) stddev-sans-first:11.49 09:20:30 INFO - PROCESS | 6165 | Values: 108.0 85.0 87.0 85.0 120.0 84.0 88.0 85.0 87.0 85.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:20:30 INFO - PROCESS | 6165 | Values: 93.0 94.0 93.0 94.0 92.0 92.0 93.0 93.0 93.0 93.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#7] imaging-desaturate Cycles:10 Average:101.70 Median:85.00 stddev:23.66 (27.8%) stddev-sans-first:24.22 09:20:30 INFO - PROCESS | 6165 | Values: 84.0 107.0 114.0 143.0 141.0 85.0 86.0 85.0 86.0 86.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:58.50 stddev:5.30 (9.1%) stddev-sans-first:5.61 09:20:30 INFO - PROCESS | 6165 | Values: 59.0 60.0 56.0 57.0 57.0 59.0 58.0 74.0 58.0 56.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:69.00 stddev:4.26 (6.2%) stddev-sans-first:4.15 09:20:30 INFO - PROCESS | 6165 | Values: 64.0 73.0 62.0 77.0 71.0 68.0 67.0 69.0 69.0 68.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:0.70 (0.9%) stddev-sans-first:0.71 09:20:30 INFO - PROCESS | 6165 | Values: 81.0 81.0 82.0 82.0 81.0 82.0 81.0 82.0 81.0 83.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:138.00 stddev:29.59 (21.4%) stddev-sans-first:31.21 09:20:30 INFO - PROCESS | 6165 | Values: 138.0 138.0 136.0 136.0 138.0 138.0 136.0 137.0 231.0 141.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:170.00 stddev:3.46 (2.0%) stddev-sans-first:2.15 09:20:30 INFO - PROCESS | 6165 | Values: 179.0 170.0 171.0 169.0 170.0 170.0 169.0 170.0 167.0 175.0 09:20:30 INFO - PROCESS | 6165 | 09:20:30 INFO - PROCESS | 6165 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:1.17 (1.7%) stddev-sans-first:1.09 09:20:30 INFO - PROCESS | 6165 | Values: 70.0 68.0 68.0 68.0 68.0 68.0 67.0 71.0 68.0 68.0 09:20:30 INFO - PROCESS | 6165 | -------- Summary: end -------- 09:20:30 INFO - PROCESS | 6165 | 09:20:31 INFO - TEST-INFO | 6165: exit 0 09:20:31 INFO - TEST-OK | kraken | took 58654ms 09:20:31 INFO - SUITE-END | took 1173s 09:20:31 INFO - Completed test suite (00:19:33) 09:20:31 INFO - javascript benchmark 09:20:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.168207948013, 3364.5794275715534, 3385.8984230473093, 3404.3361544498825, 3426.2462258303162, 4499.100179964008, 5457.645253058558, 5561.0, 5608.046632072547, 5678.063119585268, 5621.409751976045, 5622.128570071635, 5725.4847063764355, 5765.769369225236, 5770.567207459437, 5035.244713292998, 5071.31537242473, 5107.233914912756, 5118.385793704742, 5218.347706536683, 5246.501582349671, 5284.894302113956, 5289.994900968823, 5308.362996440414, 5313.123334649778, 5063.924041139386, 5129.358830146231, 5153.123968925292, 5170.172772356443, 5259.894802103956, 5116.846494605168, 5119.692818430869, 5133.25567792668, 5143.228515722634, 5147.639665223436, 5057.418396884369, 5093.465186155438, 5093.617978651579, 5109.923351149736, 5113.616478764107, 5526.640768350045, 5581.190727344512, 5596.496315331599, 5615.298087739033, 5670.376258611549, 6037.63774173551, 6127.632342059446, 6207.534434917399, 6207.906881396782, 6222.377762223787, 6151.96924015377, 6239.87520249597, 6244.594101383441, 6257.374262573751, 6257.5619706620555, 5778.913316300259, 5787.739551720183, 5801.825945221608, 5807.041838096751, 5811.186433899257, 6162.537809664203, 6169.475594574512, 6256.249250090018, 6307.495400368051, 6308.968455157695, 6004.969975150184, 6075.605085669506, 6079.787207447718, 6089.299730530957, 6102.389761023862, 6192.102145188923, 6199.256089269316, 6200.596961197508, 6214.8135555933395, 6218.626882387071, 6021.909671354933, 6078.270607527125, 6094.8943112006145, 6139.7988032087915, 6168.784092556739, 6131.448169664752, 6160.168377269011, 6190.907136392868, 6191.876162476725, 6193.071039344134, 4897.1674815281485, 4913.11563918491, 4922.286268491049, 4927.088488629615, 4965.503449655005, 3579.8926032219074, 4339.0, 4454.532274111171, 4481.775911204492, 4534.569215924482, 5638.323401191883, 5675.5459563234845, 5687.943120568824, 5691.9715401423555, 5696.3449203341315, 6269.0, 6277.058441233851, 6278.246610406781, 6291.276503202099, 6299.055141728777, 5528.723563821793, 5555.055640541037, 5611.7755289788765, 5722.284714410699, 5726.799562015392, 5342.332208473941, 5354.250404943311, 5355.892882142335, 5357.0, 5358.571314294847, 6249.281332646804, 6293.748250070037, 6307.495400368051, 6333.303336632966, 6344.302126766144, 6128.0, 6166.0, 6189.319174890848, 6292.2134733158355, 6306.747730090745, 6308.08532762756, 6330.082138090043, 6331.96834015827, 6336.619802811846, 6356.0, 6141.232345956755, 6149.72326245329, 6158.630482171084, 6188.195534580475, 6190.226221722285, 6132.049532322497, 6144.955357589131, 6215.160953271251, 6223.751049957951, 6243.594166379171, 6072.848178795565, 6073.089036644538, 6088.1895072930565, 6097.725602347817, 6103.877922441615, 6027.638341699512, 6062.757489700362, 6063.545234107548, 6065.727042283196, 6092.360302168208, 5775.133729940542, 5843.737031833492, 5882.970585147047, 5964.105384192406, 5984.072468767348, 6046.516278697605, 6058.90911636317, 6072.514198864169, 6092.756289748538, 6104.450599445893, 5909.172715819703, 5927.4665280125, 5944.6730429826775, 5963.075723262901, 5992.43071908168], "value": 5660.803241821335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.48918635432807, 503.9168537191364, 506.92025984652685, 527.180234734987, 536.2438961064897, 26091.608625870602, 37164.44253336199, 38439.0, 38516.0, 38572.228555428876, 40264.19471610566, 40814.59185408145, 40943.566975155874, 41007.38488922661, 41024.58975410245, 8395.328373730117, 9404.81190376192, 9634.47010414427, 9638.37350572214, 9642.614295428175, 8394.034686011126, 11433.256838305526, 11781.23421977573, 11795.646130616095, 11847.111466639994, 11399.772004559904, 11537.826932596017, 11550.53797848085, 11558.248713833616, 11609.361485118316, 8422.831543369159, 11285.379304138269, 11293.54825806975, 11456.5417383305, 11464.770704585944, 11338.376389298584, 11482.253653512578, 11497.5975840846, 11504.424778761113, 11518.827217591743, 36217.63782362195, 36708.26583468342, 36813.711520096935, 36836.71071512511, 36873.44689829655, 2883.8608749543814, 3167.904962851118, 3170.381775553768, 3210.598675165604, 3244.545763593099, 27454.17637470879, 28691.56962645562, 28786.712132878612, 28849.56725649117, 28996.855015724996, 2107.557412943284, 2600.2979195617268, 2601.8178727489085, 2633.394319306551, 2724.9318767030786, 468512.0, 469993.0, 470475.0, 471221.0, 471475.99999999657, 62645.0, 62645.0, 62997.0, 63068.369316307166, 63103.0, 59012.70493647504, 68817.0, 68924.96612550816, 68938.65530672314, 68965.0, 16896.746548801777, 16990.915045424696, 17074.060926648905, 17096.57258568546, 17131.400400986156, 11719.355435450962, 12062.758744825054, 12622.873771262353, 12939.158954667917, 12967.740645187043, 19339.90330048341, 19340.8065919339, 19445.90277048634, 19461.610767784565, 19467.318643847397, 6547.9017814732815, 6787.355201255873, 8108.716194933149, 8164.877526837102, 8212.876806847902, 946.9005754395689, 997.1574019953129, 1032.6798692405378, 1077.6497638267595, 1110.9611163609236, 26641.733582664314, 27157.864210679214, 27409.45181096367, 28827.991020314188, 29642.85178574093, 1088.6081010836092, 1096.8957948994832, 1120.4061847220985, 1144.4106285263097, 1221.6090850927617, 14927.552173434815, 15014.774778378332, 15026.549203523911, 15381.00023498469, 15469.613259668598, 598155.0, 602609.0, 606863.0, 607979.0, 608692.0, 57711.71144144337, 59777.40222597718, 59883.0, 60022.99999999913, 60055.699721501114, 1199.7840388730112, 1283.4545318239711, 1360.5034162530605, 1417.3480199108526, 1441.128117488946, 28300.292492687026, 28548.858045677942, 28772.856135720023, 28915.421691566473, 29044.27389315284, 44407.0, 46436.53563464457, 47201.527984719716, 47285.763571181924, 47288.52711472979, 345863.0, 347779.78330325574, 348328.0, 349018.0, 350799.0, 19218.61562768709, 25993.610095848955, 26656.333591659586, 26826.731732682423, 26983.785729642605, 35044.29911401706, 43887.0, 45235.773821132, 45357.77321113373, 45662.31506527338, 35214.0, 36694.0, 37262.813685931396, 37501.81249093737, 38227.42658860175, 29740.70259297466, 30574.471276436536, 35102.64897350994, 36372.363243654625, 37374.06564835901, 31457.898973535815, 35609.46585801267, 36762.63237367699, 37058.44412333871, 37108.44337334996, 26953.86523067372, 30581.54127688042, 30661.54007689842, 30875.140607424073, 32162.51756223705, 21613.567728645652, 24181.758182418653, 24343.713598663955, 24779.380515487257, 25012.62481062822], "value": 19380.270321033382, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.6133020305203, 398.5158032989918, 400.5793916387793, 408.9795510224488, 413.4831460674157, 19228.423147305602, 19269.518262043457, 20027.399178024647, 20039.799602003975, 20424.0, 16676.33294668215, 19626.92051937143, 19713.901430492828, 20617.484562885937, 20631.0, 5699.344575373843, 5839.591228613998, 6180.783672571461, 6180.907286390685, 6181.6600086995195, 5048.545630893219, 5864.4135586441225, 6152.384761523835, 6161.938380616181, 6163.291221509517, 6078.027515597505, 6079.78720744774, 6101.237345331833, 6112.297085835142, 6128.754849806002, 5499.0, 5887.8528036799, 6088.360722124202, 6089.817305480843, 6090.908636370458, 5468.070428027245, 5660.971695141539, 5661.320641523002, 5675.602707810455, 5681.0910254359105, 13152.802707959292, 13357.532486363029, 13390.464381424732, 13407.798883016763, 13447.731045379038, 2047.0, 2091.8117369436673, 2096.716943212662, 2239.3393948785147, 2240.439890027493, 9293.488858112802, 9952.203823694159, 9956.950215248877, 10085.495725213783, 10110.898891011142, 1685.1153144599184, 1712.9743053854202, 1749.2653085704035, 1765.7181335262067, 1778.7776527934009, 88088.97475910913, 99396.00603994013, 117925.0, 120581.79418205882, 123246.76753232532, 11363.090952723762, 11536.192466527431, 11651.951324380847, 11690.766184676258, 11900.523979040914, 13116.147450415692, 13634.250116243702, 13659.248741319125, 15017.474388396353, 15056.548303550911, 7575.583342916082, 7599.734009309647, 8339.791505212419, 8512.787180320418, 8633.568321583896, 8294.419390642717, 8298.502089874626, 8320.500769953702, 8390.622421991025, 8435.95782021086, 4408.096340250241, 4488.820447182142, 4508.48199192575, 4508.616767574727, 4574.4968053514085, 1911.3405874973112, 1946.7469229000424, 1952.681939690703, 1957.3247229705728, 1988.413418041681, 924.990750092504, 983.6508039645965, 987.0603027512876, 998.8152191844532, 1014.9441780702132, 18825.623487530174, 19180.904095479713, 19601.509962250773, 19836.10737516815, 20157.19371225164, 817.8854960305562, 825.7729124490813, 951.7668171297938, 994.080475560104, 995.4425521707867, 8454.661813527513, 9801.50992450389, 10380.480975951172, 10388.0, 10402.375857448576, 576733.0, 599373.0, 602108.0, 603649.0, 608984.0, 118992.40503797599, 122986.0, 123973.38013310057, 124913.0, 125613.37193313974, 1020.5560581147224, 1021.933574317667, 1046.3303485769113, 1047.2773786087575, 1049.6955882793904, 14139.929300353433, 17232.827671723124, 17493.212805423533, 17530.211140498453, 17534.73697894558, 48199.75900120617, 48989.755051224514, 49354.0, 50360.985560577166, 51911.74044129755, 74025.6298718503, 75232.99068037217, 76648.0, 79014.41971160659, 80374.598127009, 11137.276077054965, 11628.94185529067, 11806.0, 12030.398480076135, 12143.574974875837, 7120.501564890616, 12366.712158250752, 12373.13388062827, 12642.557510487457, 12740.989103101485, 11072.833907491233, 11388.886111138783, 11404.543818247177, 11446.656600302003, 11532.423378831192, 10947.945260273647, 11040.834387484034, 11171.218014739216, 11408.201425900108, 11466.484008219817, 11145.72135696614, 11329.603463878724, 11658.300501969909, 11700.824487632863, 11748.58879939198, 11810.05519558416, 11828.46771895284, 11993.201019847093, 12007.699807504881, 12073.939630301793, 11076.612318569134, 11168.385738784445, 11477.426128693365, 11500.884991149982, 11516.30902145874], "value": 9460.679238545037, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.7170565886825, 1580.0677600215874, 1600.359856057577, 1813.2112531048995, 1960.8725432846863, 7885.763427097189, 8638.740837774869, 8843.425177363475, 8846.336524760636, 8857.689980850671, 8396.706115285966, 8648.264897483707, 8666.91333086669, 8671.653133874637, 8682.565871706422, 2111.1766411099684, 2376.7979721723673, 2404.2186289455954, 2433.245693834917, 2456.361346050022, 2433.7444568320248, 2457.1645640482234, 2462.175171317611, 2462.495188486356, 2465.272744540365, 2387.7134743830766, 2409.5421869844763, 2417.2385698505004, 2417.4802417480237, 2425.320910145162, 2115.693224482457, 2333.5916214662366, 2409.7590240975937, 2423.6849209602815, 2438.829281950264, 2416.6012607919674, 2444.8655323957173, 2451.2768733223743, 2453.0065323543995, 2453.1659235859715, 8473.661053557851, 8535.615897284575, 8539.35954803386, 8560.571971401467, 8565.95717021411, 5101.4133374662015, 5332.440093790135, 5335.573154147659, 5379.1662292344745, 5385.165299378603, 3486.9128271793156, 3525.2420729543273, 3581.8209089545417, 3584.7490675652716, 3599.2801439711902, 1014.4471263161521, 1034.1881622926041, 1035.0787798858946, 1041.1254546181244, 1046.1055797293282, 10980.0, 11159.888401115966, 11164.609238676689, 11169.776604467865, 11180.937810908032, 4533.252013417749, 4602.194615942197, 4821.614270858323, 4826.758662066883, 4840.2255639098275, 9823.852642210373, 9824.214062875095, 9830.705078847646, 9835.065668761456, 9840.950795245977, 5297.099493086184, 5303.36359636846, 5320.920186197211, 5360.088784906575, 5361.865953351119, 3977.92044159121, 4356.0198955235455, 4496.707713998645, 4507.549245075466, 4875.19559272716, 4057.127717540743, 4112.424260603518, 4126.401671757579, 4181.60274773896, 4220.430241917302, 1179.4397661111075, 1265.949362025527, 1296.0797833538293, 1312.527490103562, 1319.874611911886, 843.9324854011664, 878.1701292278735, 889.155302462663, 893.6425429828121, 908.3513423136985, 5633.63381380209, 5787.565932555075, 5803.564732645069, 5808.419158084243, 5940.376260492672, 897.5646811296516, 909.949952752605, 930.1767935377104, 935.4200395754675, 994.1897353656747, 3767.7362584619364, 3835.1179228777537, 3871.709621778378, 3913.96086039136, 3929.842806287774, 10100.292979491513, 10427.895721042843, 10591.735206619896, 10603.84094238587, 10632.89367106319, 10581.57673693059, 10664.573417063386, 10679.359238445719, 10736.838947415796, 10951.069159121405, 7015.912533557307, 7950.761477155695, 8046.396520261003, 8200.958995204986, 8214.917850821535, 5627.465390787868, 5629.408912064338, 5869.970650146722, 5890.852728681817, 5919.830633725432, 8398.83202335962, 9161.541922903962, 9313.767155821159, 9701.466419347003, 9738.951305243429, 9122.361434699773, 9564.187044101329, 9836.311458198144, 9968.40189588627, 10114.747131321776, 6886.173659160932, 7270.127584689871, 7503.9249607503225, 7515.774526764206, 7526.134494533198, 8018.83962320762, 8032.357411407191, 8062.992125984252, 8084.555349455602, 8130.715424960098, 7134.750283740044, 7192.964035179791, 7210.423166146591, 7332.046833911771, 7346.889796652948, 6889.276625954403, 7043.401310888632, 7086.007958885657, 7151.356377926011, 7231.783046508614, 6735.393814556517, 6828.692708828015, 6831.795046148623, 6837.418819400406, 6843.486738494533, 4624.606908412688, 4770.737609431376, 4895.363602731622, 4910.140725373117, 4918.991606720542, 4232.724872883253, 4835.274708793709, 4895.290182923385, 4934.481879402611, 4962.156433406401], "value": 4535.321546422953, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.61531239543956, 318.6568613294961, 322.70311313591503, 327.6837851473328, 331.03833364077366, 5142.228665700147, 5681.460261275178, 5914.733836977335, 5960.314563825162, 5985.5810093293485, 5851.297844258695, 5855.560832937525, 5889.322727886294, 5901.114832775086, 5903.49820265278, 3685.5761587417524, 4021.0349516116066, 4150.2647617144585, 4451.1765323415275, 4505.526919673436, 3976.0, 4062.5531191568903, 4423.513413524526, 4432.268675668511, 4513.593776560109, 3866.99458140884, 4288.571142885702, 4427.645788336941, 4432.002799370148, 4434.933475997863, 3893.046203680088, 4101.302778527627, 4149.7302675326155, 4200.810963506649, 4421.137878113769, 3489.0, 3809.066778639251, 3813.427985802124, 3827.598102199257, 3829.885103446873, 4942.505749425065, 5269.420363759983, 5480.808171713971, 5616.157576363578, 5718.59969802114, 4878.146324393217, 5084.211947148198, 5112.488751124858, 5155.40712818027, 5163.561097306733, 6128.0195168773225, 6178.474829639486, 6201.844953876144, 6224.576482934368, 6248.250209974832, 3568.553930758655, 3731.2910546996254, 4278.465191851019, 4293.377460268243, 4294.141171765659, 6193.009118541012, 6519.576227545194, 6582.901256481156, 6584.967075164594, 6589.769358072444, 3563.501109844624, 4317.877351888532, 4330.7401555906745, 4337.175936572105, 4341.696081274344, 4406.9559304407185, 4409.3606427068535, 4410.2282100632265, 4416.271315232951, 4419.779011049499, 4779.187538118528, 4998.375203099613, 5006.374203224597, 5017.0, 5043.3191519145175, 6278.717457714414, 6318.747250109944, 6324.747010119544, 6340.809775706736, 6359.8092057238355, 1211.8969887559656, 1437.4322142753554, 1467.8972471927077, 1479.8964072515037, 1479.9260036998107, 897.1611543207122, 926.4898215908026, 957.8084383123403, 968.4673429613684, 972.2465089555651, 5170.922436163461, 5466.890662186734, 5803.111915016864, 5972.850678733066, 5984.401559843981, 6579.17760279965, 6587.736490540325, 6592.604443733391, 6613.933860661428, 6614.470842332602, 5663.725661726078, 5977.880442391128, 5982.521398288127, 6006.909896351558, 6021.036634138517, 2888.812227205225, 2995.1613548206537, 3170.906037417087, 3347.9665203347654, 3459.7578169528397, 5190.429052804188, 5587.44125587438, 6527.412532872065, 6575.178102737158, 6761.763338283136, 6619.437347825488, 6654.70053847578, 6688.197416310074, 6688.899666505006, 6710.899336509956, 6161.568690191733, 6356.841078973062, 6552.180977377828, 6774.3225677431865, 6797.0, 6484.0, 6486.189226346707, 6507.219133703797, 6523.380278873499, 6529.444997175104, 6390.201224846895, 6404.391582799627, 6407.10300557913, 6418.550701450854, 6438.323975982455, 6435.678216089271, 6729.822281100885, 6774.390304872585, 6778.4916131289365, 6803.965980170067, 3775.6979441644053, 3986.920261594694, 4159.292920203512, 4243.809028593782, 4248.851290204828, 6205.5345849060595, 6512.544121911421, 6525.869482610226, 6545.181852268466, 6550.0, 6296.937030629819, 6335.4931605472375, 6343.5876668016435, 6352.140612431402, 6363.331850155852, 6264.874702506016, 6302.991521356561, 6316.873662526816, 6336.3030066693545, 6338.936610633835, 5353.437889021597, 5810.593258472036, 5869.331667707683, 6104.17593624855, 6248.656323902229, 5527.668339899458, 6121.632702037892, 6180.752769889334, 6217.253929528486, 6293.276273228637, 5130.743462826769, 5218.504242096995, 5764.913526297025, 6028.216331876828, 6048.334683184758], "value": 4553.911930933485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.1628631637819, 719.96040217788, 751.8270797716528, 761.0638914135611, 792.4848848248641, 14435.71128577429, 14878.032927859682, 14885.25573721315, 14898.553043408701, 14902.627434314147, 11811.40942952851, 13685.178889266626, 13758.10572312799, 13762.105463144888, 13762.174269543835, 732.655651843634, 909.4815954905706, 934.4019827310527, 940.1585580905086, 943.3113826906363, 897.9506127163004, 907.4555266839876, 909.381620498061, 909.5634095634067, 912.598456679059, 960.7742180587557, 964.961401543941, 984.7193549838297, 985.7387792235063, 986.7385142937128, 959.8512230604267, 971.2618410008374, 974.3666616699167, 975.11264749078, 977.5112443778111, 550.7851937744283, 569.336722718033, 569.9629524080922, 570.2415787003275, 729.6534146280527, 13298.933505332509, 13701.314934253247, 13729.656758581115, 13733.313334333343, 13744.86255137446, 12191.695207619792, 12222.572209972697, 12230.938845305805, 12405.813912791315, 12562.68593285166, 9412.482313472825, 9570.760730981712, 9594.520273986273, 9620.903790962071, 9907.405555666683, 3438.707709844641, 3497.335506253829, 3497.387957107496, 3516.771409858351, 3596.1728802375337, 79941.60029199875, 81455.9999999994, 82492.58753706193, 82671.0, 82721.0, 8559.186877246651, 8813.33899957493, 9077.364584479155, 9308.069193080637, 9553.331266811256, 19882.204711811653, 19899.418104740995, 19914.800851991295, 19938.20247190096, 20318.593628127353, 7214.74748383804, 7223.783286501413, 7232.0959880015, 7255.383292420098, 7292.598907060056, 3364.9327013459592, 3835.53973523179, 3962.445257663929, 3970.146418520032, 4008.7795171265275, 3771.698264138862, 3999.8400063997115, 4002.899202719217, 4008.6392224699916, 4010.3583426651594, 679.1917618034523, 690.1717938473752, 699.3076853914588, 713.2439614009166, 715.4598278299954, 509.0, 538.2330179494205, 572.2732130194629, 577.2841676321332, 592.3454582686156, 6816.693248803816, 7428.4428667850125, 7692.7692169235015, 7866.331361834194, 7932.325752311002, 549.7251374312843, 567.5658121537027, 582.5718097198555, 583.6177303865975, 592.1827877529068, 2851.8877637721307, 3504.684578387957, 3672.265546890633, 3860.575336712959, 3916.5300163979937, 98926.0, 99552.50223748836, 100675.49662251642, 103765.0, 103926.7643941366, 39641.0, 39662.801685991384, 39911.60088399079, 40834.774956751346, 55662.0, 74567.50864982778, 75071.0, 75787.0, 77476.61261693656, 77489.0, 7394.7781566553085, 7491.0, 7562.773116806505, 7570.735024274124, 7575.659095340833, 45582.0, 46053.76973115113, 46059.53940460687, 46383.76808115938, 46525.767371162925, 61400.07899881416, 61612.38387616067, 68299.0, 70036.08304097067, 71889.56220875657, 9770.706878793648, 11178.608748694081, 11471.885281147415, 11718.824217636913, 12365.443555039865, 11850.703732406759, 11980.442542469424, 12083.818742718691, 12331.568395106127, 12390.938045309716, 10185.490725463846, 11034.944825275823, 11179.385133817394, 11432.0, 11737.061035117, 8923.959404347674, 10844.511996960313, 11456.427178641201, 11674.533018679158, 12579.0, 11024.118070554176, 11164.385958772345, 11225.270357426742, 11527.596534121602, 11769.293842369485, 9261.0, 9368.016358282282, 9382.20815401359, 9541.570629321559, 9794.363366381447, 9373.765655858659, 9423.29325300619, 9432.245420366491, 9473.289503287418, 9485.525723713648], "value": 6681.470061593612, "unit": "score"}], "name": "dromaeo_css", "value": 7233.430435429873, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 113.0, 112.0, 111.0, 113.0, 118.0, 111.0, 111.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 120.0, 146.0, 175.0, 121.0, 145.0, 163.0, 147.0, 186.0, 120.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 188.0, 184.0, 185.0, 184.0, 181.0, 186.0, 186.0, 184.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 89.0, 92.0, 57.0, 59.0, 57.0, 79.0, 86.0, 56.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 138.0, 78.0, 78.0, 76.0, 127.0, 80.0, 78.0, 127.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 85.0, 87.0, 85.0, 120.0, 84.0, 88.0, 85.0, 87.0, 85.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 92.0, 92.0, 93.0, 93.0, 93.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 107.0, 114.0, 143.0, 141.0, 85.0, 86.0, 85.0, 86.0, 86.0], "value": 101.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 56.0, 57.0, 57.0, 59.0, 58.0, 74.0, 58.0, 56.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 62.0, 77.0, 71.0, 68.0, 67.0, 69.0, 69.0, 68.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 82.0, 82.0, 81.0, 82.0, 81.0, 82.0, 81.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 136.0, 136.0, 138.0, 138.0, 136.0, 137.0, 231.0, 141.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 170.0, 171.0, 169.0, 170.0, 170.0, 169.0, 170.0, 167.0, 175.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 68.0, 68.0, 68.0, 67.0, 71.0, 68.0, 68.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1497.2, "alertThreshold": 2.0}]} 09:20:31 INFO - Return code: 0 09:20:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:20:31 ERROR - # TBPL SUCCESS # 09:20:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:20:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:20:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:20:31 INFO - 2016-04-18 09:20:31.924 screenresolution[6169:104344] starting screenresolution argv=screenresolution get 09:20:31 INFO - 2016-04-18 09:20:31.927 screenresolution[6169:104344] Display 0: 1600x1200x32@60 09:20:31 INFO - 2016-04-18 09:20:31.932 screenresolution[6170:104347] starting screenresolution argv=screenresolution list 09:20:31 INFO - Available Modes on Display 0 09:20:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:20:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:20:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:20:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:20:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:20:32 INFO - Intel Iris: 09:20:32 INFO - Chipset Model: Intel Iris 09:20:32 INFO - Type: GPU 09:20:32 INFO - Bus: Built-In 09:20:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:20:32 INFO - Vendor: Intel (0x8086) 09:20:32 INFO - Device ID: 0x0a2e 09:20:32 INFO - Revision ID: 0x0009 09:20:32 INFO - Displays: 09:20:32 INFO - Display: 09:20:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:20:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:20:32 INFO - Display Serial Number: 1600x1200 60 09:20:32 INFO - Main Display: Yes 09:20:32 INFO - Mirror: Off 09:20:32 INFO - Online: Yes 09:20:32 INFO - Rotation: Supported 09:20:32 INFO - Return code: 0 09:20:32 INFO - Running post-action listener: _resource_record_post_action 09:20:32 INFO - Running post-run listener: _resource_record_post_run 09:20:33 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 18628096; Write bytes: 693107712; Read time: 188; Write time: 2142 09:20:33 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 373113344; Write bytes: 368658944; Read time: 28056; Write time: 767 09:20:33 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 18624000; Write bytes: 295096832; Read time: 188; Write time: 1320 09:20:33 INFO - Running post-run listener: _upload_blobber_files 09:20:33 INFO - Blob upload gear active. 09:20:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:33 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 09:20:33 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'] 09:20:33 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 09:20:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:34 INFO - (blobuploader) - INFO - Done attempting. 09:20:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:35 INFO - (blobuploader) - INFO - Done attempting. 09:20:35 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:35 INFO - Return code: 0 09:20:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/43d50b755b45ff13a46f6a540e70b3c65c2c351b0eefafb767e63b9cf833e5d8015368acf9f697dfdd7ca992289427ab6e4f20c539f9d0cf154325dfa294b9c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a20bc05aafe3105ec67265c5572a848e8f25a31d27b9ce2e8a7c16fce168eaf4b265094fa33fa95560749d071c94a449a5e9d048d8433270aca57554f6f7e51d"} 09:20:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:35 INFO - Contents: 09:20:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/43d50b755b45ff13a46f6a540e70b3c65c2c351b0eefafb767e63b9cf833e5d8015368acf9f697dfdd7ca992289427ab6e4f20c539f9d0cf154325dfa294b9c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a20bc05aafe3105ec67265c5572a848e8f25a31d27b9ce2e8a7c16fce168eaf4b265094fa33fa95560749d071c94a449a5e9d048d8433270aca57554f6f7e51d"} 09:20:35 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:35 INFO - Copying logs to upload dir... 09:20:35 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.871710 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-18 09:20:35.791161) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 09:20:35.795096) ========= 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.kEt4FBCXnT/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.l79qhjm7wI/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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/43d50b755b45ff13a46f6a540e70b3c65c2c351b0eefafb767e63b9cf833e5d8015368acf9f697dfdd7ca992289427ab6e4f20c539f9d0cf154325dfa294b9c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a20bc05aafe3105ec67265c5572a848e8f25a31d27b9ce2e8a7c16fce168eaf4b265094fa33fa95560749d071c94a449a5e9d048d8433270aca57554f6f7e51d"} build_url:https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011302 build_url: 'https://queue.taskcluster.net/v1/task/SHujVydVR_25kJMvfIhthA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/43d50b755b45ff13a46f6a540e70b3c65c2c351b0eefafb767e63b9cf833e5d8015368acf9f697dfdd7ca992289427ab6e4f20c539f9d0cf154325dfa294b9c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a20bc05aafe3105ec67265c5572a848e8f25a31d27b9ce2e8a7c16fce168eaf4b265094fa33fa95560749d071c94a449a5e9d048d8433270aca57554f6f7e51d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 09:20:35.825236) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:20:35.825611) ========= 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.kEt4FBCXnT/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.l79qhjm7wI/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.005312 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:20:35.875174) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 09:20:35.875637) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 09:20:35.876510) ========= ========= Total master_lag: 3.31 =========