Slow login when www.nextcloud.com inaccessible

[marmeladema]

My nextcloud server is connected to internet, but it appears that this morning www.nextcloud.com is inacssessible. This cause very slow login from desktop web browser (and maybe login failure from nextcloud android app, but it has to be investigated if this current issue is resolved).

Steps to reproduce

1. Logout if you are already logged in
2. Login

Expected behaviour

Login should succeed "instantly"

Actual behaviour

Login succeed after dozens of second

Server configuration

Operating system:
GNU/Linux Debian 9.3
Web server:
Apache/2.4.25 behind nginx as reverse proxy
Database:
mariadb Ver 15.1 Distrib 10.1.26-MariaDB, for debian-linux-gnu (x86_64) using readline 5.2
PHP version:
PHP 7.0.27-0+deb9u1 (cli) (built: Jan 5 2018 13:51:52) ( NTS )
Nextcloud version: (see Nextcloud admin page)
13
Updated from an older Nextcloud/ownCloud or fresh install:
12.0.4
Where did you install Nextcloud from:
Manual install from sources
Signing status:
No errors have been found.

List of activated apps:

Enabled:
  - activity: 2.6.1
  - admin_audit: 1.3.0
  - announcementcenter: 3.2.1
  - bookmarks: 0.10.1
  - bruteforcesettings: 1.0.3
  - calendar: 1.6.0
  - comments: 1.3.0
  - contacts: 2.1.0
  - dav: 1.4.6
  - federatedfilesharing: 1.3.1
  - federation: 1.3.0
  - files: 1.8.0
  - files_external: 1.4.1
  - files_pdfviewer: 1.2.0
  - files_sharing: 1.5.0
  - files_texteditor: 2.5.1
  - files_trashbin: 1.3.0
  - files_versions: 1.6.0
  - files_videoplayer: 1.2.0
  - gallery: 18.0.0
  - logreader: 2.0.0
  - lookup_server_connector: 1.1.0
  - nextcloud_announcements: 1.2.0
  - notes: 2.3.2
  - notifications: 2.1.2
  - oauth2: 1.1.0
  - password_policy: 1.3.0
  - provisioning_api: 1.3.0
  - serverinfo: 1.3.0
  - sharebymail: 1.3.0
  - survey_client: 1.1.0
  - systemtags: 1.3.0
  - twofactor_backupcodes: 1.2.3
  - updatenotification: 1.3.0
  - user_external: 0.4
  - workflowengine: 1.3.0
Disabled:
  - encryption
  - external
  - firstrunwizard
  - gluusso
  - news
  - tasks
  - theming
  - user_ldap

Nextcloud configuration:

{
    "system": {
        "instanceid": "***REMOVED SENSITIVE VALUE***",
        "passwordsalt": "***REMOVED SENSITIVE VALUE***",
        "secret": "***REMOVED SENSITIVE VALUE***",
        "trusted_domains": [
            "127.0.0.1:8081"
        ],
        "datadirectory": "***REMOVED SENSITIVE VALUE***",
        "overwrite.cli.url": "http:\/\/127.0.0.1:8081",
        "dbtype": "mysql",
        "version": "13.0.0.14",
        "dbname": "***REMOVED SENSITIVE VALUE***",
        "dbhost": "***REMOVED SENSITIVE VALUE***",
        "dbtableprefix": "oc_",
        "dbuser": "***REMOVED SENSITIVE VALUE***",
        "dbpassword": "***REMOVED SENSITIVE VALUE***",
        "logtimezone": "UTC",
        "installed": true,
        "updater.server.url": "https:\/\/updates.nextcloud.com\/updater_server\/",
        "updater.release.channel": "stable",
        "maintenance": false,
        "theme": "",
        "loglevel": 2,
        "appstore.experimental.enabled": true,
        "trusted_proxies": "***REMOVED SENSITIVE VALUE***",
        "overwritehost": "***REMOVED SENSITIVE VALUE***",
        "overwriteprotocol": "https",
        "updater.secret": "***REMOVED SENSITIVE VALUE***"
    }
}

Are you using external storage, if yes which one: local

Are you using encryption: no

Are you using an external user-backend, if yes which one: no

Client configuration

Browser:
Firefox 58.0 (64-bit)
Operating system:
GNU/Linux Debian buster/sid

Logs

Web server error log

Web server error log

==> /var/log/apache2/error.log <==
[Wed Feb 14 09:56:17.079616 2018] [authz_core:debug] [pid 12119] mod_authz_core.c(809): [client 127.0.0.1:48650] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:17.079641 2018] [authz_core:debug] [pid 12119] mod_authz_core.c(809): [client 127.0.0.1:48650] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:17.079694 2018] [authz_core:debug] [pid 12119] mod_authz_core.c(809): [client 127.0.0.1:48650] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:17.079700 2018] [authz_core:debug] [pid 12119] mod_authz_core.c(809): [client 127.0.0.1:48650] AH01626: authorization result of <RequireAny>: granted

==> /var/log/apache2/access.log <==
127.0.0.1 - - [14/Feb/2018:09:55:59 +0100] "GET /index.php/apps/logreader/poll?lastReqId=aUQPiC77mgI0njMtQMdL HTTP/1.0" 200 816 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
127.0.0.1 - - [14/Feb/2018:09:56:17 +0100] "POST /index.php/login HTTP/1.0" 303 956 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"

==> /var/log/apache2/error.log <==
[Wed Feb 14 09:56:47.227019 2018] [authz_core:debug] [pid 12512] mod_authz_core.c(809): [client 127.0.0.1:48652] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:47.227034 2018] [authz_core:debug] [pid 12512] mod_authz_core.c(809): [client 127.0.0.1:48652] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:47.227113 2018] [authz_core:debug] [pid 12512] mod_authz_core.c(809): [client 127.0.0.1:48652] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:47.227119 2018] [authz_core:debug] [pid 12512] mod_authz_core.c(809): [client 127.0.0.1:48652] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:47.227156 2018] [authz_core:debug] [pid 12512] mod_authz_core.c(809): [client 127.0.0.1:48652] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:47.227159 2018] [authz_core:debug] [pid 12512] mod_authz_core.c(809): [client 127.0.0.1:48652] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:47.274428 2018] [deflate:debug] [pid 12512] mod_deflate.c(853): [client 127.0.0.1:48652] AH01384: Zlib: Compressed 45436 to 7139 : URL /index.php/apps/files/

==> /var/log/apache2/access.log <==
127.0.0.1 - - [14/Feb/2018:09:56:47 +0100] "GET /index.php/apps/files/ HTTP/1.0" 200 8048 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"

==> /var/log/apache2/error.log <==
[Wed Feb 14 09:56:49.404637 2018] [authz_core:debug] [pid 12115] mod_authz_core.c(809): [client 127.0.0.1:48654] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.404658 2018] [authz_core:debug] [pid 12115] mod_authz_core.c(809): [client 127.0.0.1:48654] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:49.404716 2018] [authz_core:debug] [pid 12115] mod_authz_core.c(809): [client 127.0.0.1:48654] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.404723 2018] [authz_core:debug] [pid 12115] mod_authz_core.c(809): [client 127.0.0.1:48654] AH01626: authorization result of <RequireAny>: granted

==> /var/log/apache2/access.log <==
127.0.0.1 - - [14/Feb/2018:09:56:49 +0100] "GET /index.php/avatar/admin/32?v=0 HTTP/1.0" 304 247 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"

==> /var/log/apache2/error.log <==
[Wed Feb 14 09:56:49.594849 2018] [authz_core:debug] [pid 12117] mod_authz_core.c(809): [client 127.0.0.1:48656] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.594873 2018] [authz_core:debug] [pid 12117] mod_authz_core.c(809): [client 127.0.0.1:48656] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:49.594954 2018] [authz_core:debug] [pid 12117] mod_authz_core.c(809): [client 127.0.0.1:48656] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.594959 2018] [authz_core:debug] [pid 12117] mod_authz_core.c(809): [client 127.0.0.1:48656] AH01626: authorization result of <RequireAny>: granted

==> /var/log/apache2/access.log <==
127.0.0.1 - - [14/Feb/2018:09:56:49 +0100] "GET /ocs/v2.php/apps/notifications/api/v2/notifications HTTP/1.0" 200 663 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"

==> /var/log/apache2/error.log <==
[Wed Feb 14 09:56:49.629176 2018] [authz_core:debug] [pid 12185] mod_authz_core.c(809): [client 127.0.0.1:48660] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.629176 2018] [authz_core:debug] [pid 12116] mod_authz_core.c(809): [client 127.0.0.1:48658] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.629189 2018] [authz_core:debug] [pid 12185] mod_authz_core.c(809): [client 127.0.0.1:48660] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:49.629204 2018] [authz_core:debug] [pid 12116] mod_authz_core.c(809): [client 127.0.0.1:48658] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:49.629262 2018] [authz_core:debug] [pid 12116] mod_authz_core.c(809): [client 127.0.0.1:48658] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.629267 2018] [authz_core:debug] [pid 12116] mod_authz_core.c(809): [client 127.0.0.1:48658] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:49.629268 2018] [authz_core:debug] [pid 12185] mod_authz_core.c(809): [client 127.0.0.1:48660] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.629282 2018] [authz_core:debug] [pid 12185] mod_authz_core.c(809): [client 127.0.0.1:48660] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:49.630171 2018] [authz_core:debug] [pid 12124] mod_authz_core.c(809): [client 127.0.0.1:48662] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.630184 2018] [authz_core:debug] [pid 12124] mod_authz_core.c(809): [client 127.0.0.1:48662] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:49.630259 2018] [authz_core:debug] [pid 12124] mod_authz_core.c(809): [client 127.0.0.1:48662] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:49.630274 2018] [authz_core:debug] [pid 12124] mod_authz_core.c(809): [client 127.0.0.1:48662] AH01626: authorization result of <RequireAny>: granted

==> /var/log/apache2/access.log <==
127.0.0.1 - - [14/Feb/2018:09:56:49 +0100] "GET /index.php/apps/gallery/config?extramediatypes=1 HTTP/1.0" 200 918 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
127.0.0.1 - - [14/Feb/2018:09:56:49 +0100] "PROPFIND /remote.php/webdav/ HTTP/1.0" 207 3848 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
127.0.0.1 - - [14/Feb/2018:09:56:49 +0100] "GET /index.php/apps/systemtags/lastused HTTP/1.0" 200 816 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"

==> /var/log/apache2/error.log <==
[Wed Feb 14 09:56:50.669824 2018] [authz_core:debug] [pid 12119] mod_authz_core.c(809): [client 127.0.0.1:48666] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:50.669824 2018] [authz_core:debug] [pid 12118] mod_authz_core.c(809): [client 127.0.0.1:48664] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:50.669844 2018] [authz_core:debug] [pid 12119] mod_authz_core.c(809): [client 127.0.0.1:48666] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:50.669860 2018] [authz_core:debug] [pid 12118] mod_authz_core.c(809): [client 127.0.0.1:48664] AH01626: authorization result of <RequireAny>: granted
[Wed Feb 14 09:56:50.669929 2018] [authz_core:debug] [pid 12119] mod_authz_core.c(809): [client 127.0.0.1:48666] AH01626: authorization result of Require all granted: granted
[Wed Feb 14 09:56:50.669943 2018] [authz_core:debug] [pid 12119] mod_authz_core.c(809): [client 127.0.0.1:48666] AH01626: authorization result of <RequireAny>: granted

==> /var/log/apache2/access.log <==
127.0.0.1 - - [14/Feb/2018:09:56:50 +0100] "GET /ocs/v1.php/apps/files_external/api/v1/mounts?format=json HTTP/1.0" 200 907 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
127.0.0.1 - - [14/Feb/2018:09:56:50 +0100] "GET /index.php/apps/files/ajax/getstoragestats.php?dir=%2F HTTP/1.0" 200 1048 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"

Nextcloud log (data/nextcloud.log)

Nextcloud log

{"reqId":"nuFSdZlmUC4Z19uvqXtu","level":3,"time":"2018-02-14T08:25:09+00:00","remoteAddr":"127.0.0.1","user":"admin","app":"internet_connection_check","method":"GET","url":"\/index.php\/settings\/ajax\/checksetup","message":"Exception: {\"Exception\":\"GuzzleHttp\\\\Exception\\\\ConnectException\",\"Message\":\"cURL error 7: Failed to connect to www.nextcloud.com port 80: No route to host\",\"Code\":0,\"Trace\":\"#0 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(103): GuzzleHttp\\\\Exception\\\\RequestException::wrapException(Object(GuzzleHttp\\\\Message\\\\Request), Object(GuzzleHttp\\\\Ring\\\\Exception\\\\ConnectException))\\n#1 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(132): GuzzleHttp\\\\RequestFsm->__invoke(Object(GuzzleHttp\\\\Transaction))\\n#2 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/react\\\/promise\\\/src\\\/FulfilledPromise.php(25): GuzzleHttp\\\\RequestFsm->GuzzleHttp\\\\{closure}(*** sensitive parameters replaced ***)\\n#3 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/ringphp\\\/src\\\/Future\\\/CompletedFutureValue.php(55): React\\\\Promise\\\\FulfilledPromise->then(Object(Closure), NULL, NULL)\\n#4 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Message\\\/FutureResponse.php(43): GuzzleHttp\\\\Ring\\\\Future\\\\CompletedFutureValue->then(Object(Closure), NULL, NULL)\\n#5 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(134): GuzzleHttp\\\\Message\\\\FutureResponse::proxy(Object(GuzzleHttp\\\\Ring\\\\Future\\\\CompletedFutureArray), Object(Closure))\\n#6 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Client.php(165): GuzzleHttp\\\\RequestFsm->__invoke(Object(GuzzleHttp\\\\Transaction))\\n#7 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Client.php(125): GuzzleHttp\\\\Client->send(Object(GuzzleHttp\\\\Message\\\\Request))\\n#8 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/Http\\\/Client\\\/Client.php(138): GuzzleHttp\\\\Client->get('http:\\\/\\\/www.next...', Array)\\n#9 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(129): OC\\\\Http\\\\Client\\\\Client->get('http:\\\/\\\/www.next...')\\n#10 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(112): OC\\\\Settings\\\\Controller\\\\CheckSetupController->isSiteReachable('www.nextcloud.c...')\\n#11 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(426): OC\\\\Settings\\\\Controller\\\\CheckSetupController->isInternetConnectionWorking()\\n#12 [internal function]: OC\\\\Settings\\\\Controller\\\\CheckSetupController->check()\\n#13 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(161): call_user_func_array(Array, Array)\\n#14 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(91): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OC\\\\Settings\\\\Controller\\\\CheckSetupController), 'check')\\n#15 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(115): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OC\\\\Settings\\\\Controller\\\\CheckSetupController), 'check')\\n#16 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(47): OC\\\\AppFramework\\\\App::main('OC\\\\\\\\Settings\\\\\\\\Con...', 'check', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#17 [internal function]: OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#18 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/Route\\\/Router.php(297): call_user_func(Object(OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler), Array)\\n#19 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/base.php(998): OC\\\\Route\\\\Router->match('\\\/settings\\\/ajax\\\/...')\\n#20 \\\/mnt\\\/DATA\\\/nextcloud\\\/index.php(37): OC::handleRequest()\\n#21 {main}\",\"File\":\"\\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Exception\\\/RequestException.php\",\"Line\":49}","userAgent":"Mozilla\/5.0 (X11; Linux x86_64; rv:58.0) Gecko\/20100101 Firefox\/58.0","version":"13.0.0.14"}
{"reqId":"y4N5M62uDGk0F9MUE10H","level":3,"time":"2018-02-14T08:25:12+00:00","remoteAddr":"127.0.0.1","user":"admin","app":"internet_connection_check","method":"GET","url":"\/index.php\/settings\/ajax\/checksetup","message":"Exception: {\"Exception\":\"GuzzleHttp\\\\Exception\\\\ConnectException\",\"Message\":\"cURL error 7: Failed to connect to www.nextcloud.com port 80: No route to host\",\"Code\":0,\"Trace\":\"#0 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(103): GuzzleHttp\\\\Exception\\\\RequestException::wrapException(Object(GuzzleHttp\\\\Message\\\\Request), Object(GuzzleHttp\\\\Ring\\\\Exception\\\\ConnectException))\\n#1 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(132): GuzzleHttp\\\\RequestFsm->__invoke(Object(GuzzleHttp\\\\Transaction))\\n#2 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/react\\\/promise\\\/src\\\/FulfilledPromise.php(25): GuzzleHttp\\\\RequestFsm->GuzzleHttp\\\\{closure}(*** sensitive parameters replaced ***)\\n#3 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/ringphp\\\/src\\\/Future\\\/CompletedFutureValue.php(55): React\\\\Promise\\\\FulfilledPromise->then(Object(Closure), NULL, NULL)\\n#4 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Message\\\/FutureResponse.php(43): GuzzleHttp\\\\Ring\\\\Future\\\\CompletedFutureValue->then(Object(Closure), NULL, NULL)\\n#5 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(134): GuzzleHttp\\\\Message\\\\FutureResponse::proxy(Object(GuzzleHttp\\\\Ring\\\\Future\\\\CompletedFutureArray), Object(Closure))\\n#6 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Client.php(165): GuzzleHttp\\\\RequestFsm->__invoke(Object(GuzzleHttp\\\\Transaction))\\n#7 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Client.php(125): GuzzleHttp\\\\Client->send(Object(GuzzleHttp\\\\Message\\\\Request))\\n#8 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/Http\\\/Client\\\/Client.php(138): GuzzleHttp\\\\Client->get('http:\\\/\\\/www.next...', Array)\\n#9 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(129): OC\\\\Http\\\\Client\\\\Client->get('http:\\\/\\\/www.next...')\\n#10 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(112): OC\\\\Settings\\\\Controller\\\\CheckSetupController->isSiteReachable('www.nextcloud.c...')\\n#11 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(426): OC\\\\Settings\\\\Controller\\\\CheckSetupController->isInternetConnectionWorking()\\n#12 [internal function]: OC\\\\Settings\\\\Controller\\\\CheckSetupController->check()\\n#13 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(161): call_user_func_array(Array, Array)\\n#14 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(91): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OC\\\\Settings\\\\Controller\\\\CheckSetupController), 'check')\\n#15 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(115): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OC\\\\Settings\\\\Controller\\\\CheckSetupController), 'check')\\n#16 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(47): OC\\\\AppFramework\\\\App::main('OC\\\\\\\\Settings\\\\\\\\Con...', 'check', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#17 [internal function]: OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#18 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/Route\\\/Router.php(297): call_user_func(Object(OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler), Array)\\n#19 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/base.php(998): OC\\\\Route\\\\Router->match('\\\/settings\\\/ajax\\\/...')\\n#20 \\\/mnt\\\/DATA\\\/nextcloud\\\/index.php(37): OC::handleRequest()\\n#21 {main}\",\"File\":\"\\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Exception\\\/RequestException.php\",\"Line\":49}","userAgent":"Mozilla\/5.0 (X11; Linux x86_64; rv:58.0) Gecko\/20100101 Firefox\/58.0","version":"13.0.0.14"}
{"reqId":"rkROmpitHIGmS2IlP1Tu","level":3,"time":"2018-02-14T08:25:39+00:00","remoteAddr":"127.0.0.1","user":"admin","app":"internet_connection_check","method":"GET","url":"\/index.php\/settings\/ajax\/checksetup","message":"Exception: {\"Exception\":\"GuzzleHttp\\\\Exception\\\\ConnectException\",\"Message\":\"cURL error 7: Failed to connect to www.nextcloud.com port 80: No route to host\",\"Code\":0,\"Trace\":\"#0 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(103): GuzzleHttp\\\\Exception\\\\RequestException::wrapException(Object(GuzzleHttp\\\\Message\\\\Request), Object(GuzzleHttp\\\\Ring\\\\Exception\\\\ConnectException))\\n#1 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(132): GuzzleHttp\\\\RequestFsm->__invoke(Object(GuzzleHttp\\\\Transaction))\\n#2 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/react\\\/promise\\\/src\\\/FulfilledPromise.php(25): GuzzleHttp\\\\RequestFsm->GuzzleHttp\\\\{closure}(*** sensitive parameters replaced ***)\\n#3 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/ringphp\\\/src\\\/Future\\\/CompletedFutureValue.php(55): React\\\\Promise\\\\FulfilledPromise->then(Object(Closure), NULL, NULL)\\n#4 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Message\\\/FutureResponse.php(43): GuzzleHttp\\\\Ring\\\\Future\\\\CompletedFutureValue->then(Object(Closure), NULL, NULL)\\n#5 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(134): GuzzleHttp\\\\Message\\\\FutureResponse::proxy(Object(GuzzleHttp\\\\Ring\\\\Future\\\\CompletedFutureArray), Object(Closure))\\n#6 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Client.php(165): GuzzleHttp\\\\RequestFsm->__invoke(Object(GuzzleHttp\\\\Transaction))\\n#7 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Client.php(125): GuzzleHttp\\\\Client->send(Object(GuzzleHttp\\\\Message\\\\Request))\\n#8 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/Http\\\/Client\\\/Client.php(138): GuzzleHttp\\\\Client->get('http:\\\/\\\/www.next...', Array)\\n#9 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(129): OC\\\\Http\\\\Client\\\\Client->get('http:\\\/\\\/www.next...')\\n#10 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(112): OC\\\\Settings\\\\Controller\\\\CheckSetupController->isSiteReachable('www.nextcloud.c...')\\n#11 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(426): OC\\\\Settings\\\\Controller\\\\CheckSetupController->isInternetConnectionWorking()\\n#12 [internal function]: OC\\\\Settings\\\\Controller\\\\CheckSetupController->check()\\n#13 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(161): call_user_func_array(Array, Array)\\n#14 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(91): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OC\\\\Settings\\\\Controller\\\\CheckSetupController), 'check')\\n#15 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(115): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OC\\\\Settings\\\\Controller\\\\CheckSetupController), 'check')\\n#16 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(47): OC\\\\AppFramework\\\\App::main('OC\\\\\\\\Settings\\\\\\\\Con...', 'check', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#17 [internal function]: OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#18 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/Route\\\/Router.php(297): call_user_func(Object(OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler), Array)\\n#19 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/base.php(998): OC\\\\Route\\\\Router->match('\\\/settings\\\/ajax\\\/...')\\n#20 \\\/mnt\\\/DATA\\\/nextcloud\\\/index.php(37): OC::handleRequest()\\n#21 {main}\",\"File\":\"\\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Exception\\\/RequestException.php\",\"Line\":49}","userAgent":"Mozilla\/5.0 (X11; Linux x86_64; rv:58.0) Gecko\/20100101 Firefox\/58.0","version":"13.0.0.14"}
{"reqId":"dDJq1BiRaPHLSZ2wzZ8V","level":3,"time":"2018-02-14T08:32:21+00:00","remoteAddr":"127.0.0.1","user":"admin","app":"internet_connection_check","method":"GET","url":"\/index.php\/settings\/ajax\/checksetup","message":"Exception: {\"Exception\":\"GuzzleHttp\\\\Exception\\\\ConnectException\",\"Message\":\"cURL error 7: Failed to connect to www.nextcloud.com port 80: No route to host\",\"Code\":0,\"Trace\":\"#0 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(103): GuzzleHttp\\\\Exception\\\\RequestException::wrapException(Object(GuzzleHttp\\\\Message\\\\Request), Object(GuzzleHttp\\\\Ring\\\\Exception\\\\ConnectException))\\n#1 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(132): GuzzleHttp\\\\RequestFsm->__invoke(Object(GuzzleHttp\\\\Transaction))\\n#2 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/react\\\/promise\\\/src\\\/FulfilledPromise.php(25): GuzzleHttp\\\\RequestFsm->GuzzleHttp\\\\{closure}(*** sensitive parameters replaced ***)\\n#3 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/ringphp\\\/src\\\/Future\\\/CompletedFutureValue.php(55): React\\\\Promise\\\\FulfilledPromise->then(Object(Closure), NULL, NULL)\\n#4 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Message\\\/FutureResponse.php(43): GuzzleHttp\\\\Ring\\\\Future\\\\CompletedFutureValue->then(Object(Closure), NULL, NULL)\\n#5 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/RequestFsm.php(134): GuzzleHttp\\\\Message\\\\FutureResponse::proxy(Object(GuzzleHttp\\\\Ring\\\\Future\\\\CompletedFutureArray), Object(Closure))\\n#6 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Client.php(165): GuzzleHttp\\\\RequestFsm->__invoke(Object(GuzzleHttp\\\\Transaction))\\n#7 \\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Client.php(125): GuzzleHttp\\\\Client->send(Object(GuzzleHttp\\\\Message\\\\Request))\\n#8 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/Http\\\/Client\\\/Client.php(138): GuzzleHttp\\\\Client->get('http:\\\/\\\/www.next...', Array)\\n#9 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(129): OC\\\\Http\\\\Client\\\\Client->get('http:\\\/\\\/www.next...')\\n#10 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(112): OC\\\\Settings\\\\Controller\\\\CheckSetupController->isSiteReachable('www.nextcloud.c...')\\n#11 \\\/mnt\\\/DATA\\\/nextcloud\\\/settings\\\/Controller\\\/CheckSetupController.php(426): OC\\\\Settings\\\\Controller\\\\CheckSetupController->isInternetConnectionWorking()\\n#12 [internal function]: OC\\\\Settings\\\\Controller\\\\CheckSetupController->check()\\n#13 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(161): call_user_func_array(Array, Array)\\n#14 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(91): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OC\\\\Settings\\\\Controller\\\\CheckSetupController), 'check')\\n#15 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(115): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OC\\\\Settings\\\\Controller\\\\CheckSetupController), 'check')\\n#16 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(47): OC\\\\AppFramework\\\\App::main('OC\\\\\\\\Settings\\\\\\\\Con...', 'check', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#17 [internal function]: OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#18 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/private\\\/Route\\\/Router.php(297): call_user_func(Object(OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler), Array)\\n#19 \\\/mnt\\\/DATA\\\/nextcloud\\\/lib\\\/base.php(998): OC\\\\Route\\\\Router->match('\\\/settings\\\/ajax\\\/...')\\n#20 \\\/mnt\\\/DATA\\\/nextcloud\\\/index.php(37): OC::handleRequest()\\n#21 {main}\",\"File\":\"\\\/mnt\\\/DATA\\\/nextcloud\\\/3rdparty\\\/guzzlehttp\\\/guzzle\\\/src\\\/Exception\\\/RequestException.php\",\"Line\":49}","userAgent":"Mozilla\/5.0 (X11; Linux x86_64; rv:58.0) Gecko\/20100101 Firefox\/58.0","version":"13.0.0.14"}

Browser log

Browser log

Insert your browser log here, this could for example include:

a) The javascript console log
b) The network log
c) ...

[marmeladema]

Related to #8348 and #8352

[MorrisJobke]

cc @nickvergessen @rullzer Do you know why this is happening? Usually we only do this during the setup check on the admin settings 🤔

[marmeladema]

Maybe i missunderstood the problem ? I correlated both events because I noticed them at the same time but they might be unrelated although now that nextcloud.com is back, login is as fast as usual.

[marmeladema]

And android apps are working again.

[marmeladema]

But as you can see in the app list, I have no "xmpp jabber app" enabled. And I did not change anything before login became slow.

[MorrisJobke]

I just checked again the code and there is no other request to https://nextcloud.com made except the one in the admin settings for the setup check, but this has nothing to do with the login logic.

This cause very slow login from desktop web browser (and maybe login failure from nextcloud android app, but it has to be investigated if this current issue is resolved).

Maybe it is caused by the apps. Just to clarify: What login took long? The login via the web browser or the login via the desktop client/mobile apps?

@rullzer @camilasan Does the desktop client query something else except the typed in URL and the update server?

@tobiasKaminsky Does the Android app query something else except the typed in URL?

[nickvergessen]

Slow login always makes me assume brute force protection kicked in.
When it's fast again 12hours later, that just confirms it.

[MorrisJobke]

Slow login always makes me assume brute force protection kicked in.
When it's fast again 12hours later, that just confirms it.

🙈 Obviously I forgot about this option.

Starting with 13 we reset this on every successful login from that IP for that user: #7263

With 14 we will also show a warning if the bruteforce logic hits in (for > 5 seconds wait time only): #7848

[tobiasKaminsky]

Android app also never access nextcloud.com.
(I am sometimes developing on a local instance without internet access and this is always fast, except when bruteforce hits in ;-) )

[MorrisJobke]

and why in this case, when it activate jabber client application it load slow and it need to disable jabber client aplication. this issue persist from version 11x and in nextcloud 12 and nextcloud 13, other day i update jabber client nextcloud and it continue slow, redis and memcache is activated

That is a problem of that app - maybe @LEDfan knows more on this.

[rullzer]

Desktop client also only connects to the nextcloud server you setup your account to.

It does query the update server but that is async.

[nextcloud-bot]

Hey, this issue has been closed because the label stale is set and there were no updates for 14 days. Feel free to reopen this issue if you deem it appropriate.

(This is an automated comment from GitMate.io.)