Android: Authentication lost daily

[thgoebel]

Actual behaviour

App asks me to authenticate again each morning.

Expected behaviour

App should remember the authentication token.

Steps to reproduce

1. Log in to my organisation's OwnCloud
2. Use normally for today.
3. Wake up next morning, open app.
4. Observe Snackbar: _Authentication failed -- Change account_
5. Go to _Manage Accounts_, hit key icon and authenticate again.
6. Use normally for today.
7. Wait for tomorrow, see it fail again.

This started to happen about one or two weeks ago. Our IT help desk said they upgraded the server at that time, so I'm not sure whether it's a server or client issue.

Also not sure if it's related to #2356.

Can this problem be reproduced with the official owncloud server?
I haven't tried yet.
(url: https://demo.owncloud.org, user: test, password: test)

Environment data

Android version: 9

Device model: OnePlus 5

Stock or customized system: LineageOS 16.0

ownCloud app version: 2.10.0 from F-Droid

ownCloud server version: 10.1.0.4

OAuth2 version: 0.4.0

Logs

Web server error log

Hard to get them from my IT dept

ownCloud log (data/owncloud.log)

Could not locate data/owncloud.log. Fetched straight from logcat.

04-02 09:20:23.720 25558 25575 V AccountAuthenticator: Refreshing token!
04-02 09:20:23.720 25558 25575 D OwnCloudClient #1139: Creating OwnCloudClient
04-02 09:20:23.721 25558 25575 D OwnCloudClient: Executing PostMethod in request with id xxxxxxxxxxxxxxxxxxxxx-xxxxxxx
04-02 09:20:23.969 25558 25575 D OAuth2RefreshAccessTokenOperation: OAUTH2: raw response from POST TOKEN: {"error":"invalid_grant"}
04-02 09:20:23.969 25558 25575 E AccountAuthenticator: Failed to refresh access token

[jesmrec]

You are using OAuth2 0.4.0, will try to reproduce. This is not the same case as #2356 (i guess), so there, the session runs under app password authentication. Usually, when the snackbar with the authentication failure raises is because server returned a 4xx response over any request.

Will have a try and let you know if i find something out.

Thanks for reporting.

[thgoebel]

Let me know if there's any debugging steps I can do to help.

[jesmrec]

After 3 days i could not reproduce the problem, session keeps alive with no interruption. App version server version and OAuth2 are the same, but the device so we do not have an OnePlus. I will retry with any Android 9. Maybe a test account is helpful as well. Is it posible to send us to [email protected]?

[thgoebel]

I doubt it's device specific, because I'm getting the same on my tablet (Samsung Galaxy Tab S2 (gts28vewifi), Lineage 16, same server and app version). Forgot to mention that because I don't use it too often, sorry.

What do you mean by your last question, Is it posible to send us to [email protected]? (I don't understand the semantics :grimacing:)

[michaelstingl]

What do you mean by your last question, _Is it posible to send us to [email protected]?_ (I don't understand the semantics 😬)

Create a test account and send the credentials

[jesmrec]

With Android 9 is not reproducible either (Pixel2). At this point, the issue could be with Lineage, or something related with server.

As @michaelstingl said, a test account also can help to check if there is something wrong in the server? Do you have any specific set up?

[thgoebel]

I dug up my LG G6 (h870) running Lineage 14.1, and could not reproduce it there either :/

Also, I've asked my organisation's helpdesk if we can get a test account, let's see what they say.

[thgoebel]

Here's some more information I've gathered over the last two days:

| Device | OwnCloud v2.10.0 | OwnCloud v2.9.3 | Polybox v2.9.3 |
|-----|-----|-----|-----|
| LG G6 h870 LOS 14.1 | :+1: | :+1: | n/a |
| OP5 cheeseburger LOS 16.0 | :-1: | :+1: | :+1: |
| Galaxy Tab S2 gts28vewifi LOS 16.0 | :-1: | :+1: | n/a |
| Client source | F-Droid | F-Droid | Google Play |

I tried the branded client, too (just in case) but there seems to be not too much of a difference over the official ownCloud v.2.9.3.
I'm still waiting for a guest account.

[michaelstingl]

@thgoebel just FYI:
Next version of the Android app will have a debug log that can be enabled by users. (#2439)

[jesmrec]

@thgoebel 2.11 was released, with option to send logs. Will be appreciate logs with the problem to check if we can find out something.

[thgoebel]

Those are the logs from the app (just opening the app and seeing it fail, the logs from me logging in again are omitted):

2019/06/19 11:16:46 D: OwnCloudClient : Executing GetMethod in request with id 66905530-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:46 V: FileDisplayActivity : onPause() start
2019/06/19 11:16:46 V: FileDisplayActivity : onPause() ending
2019/06/19 11:16:46 V: FileDisplayActivity : onPause() end
2019/06/19 11:16:47 V: FileDisplayActivity : onStop() start
2019/06/19 11:16:47 V: FileDisplayActivity : onStop() ending
2019/06/19 11:16:47 V: FileDisplayActivity : onStop() end
2019/06/19 11:16:47 V: FileDisplayActivity : onSaveInstanceState() start
2019/06/19 11:16:47 V: FileDisplayActivity : onSaveInstanceState(Bundle) starting
2019/06/19 11:16:47 D: ExtendedListFragment : onSaveInstanceState()
2019/06/19 11:16:47 V: FileDisplayActivity : onSaveInstanceState() end
2019/06/19 11:16:47 V: AccountAuthenticator : Refreshing token!
2019/06/19 11:16:47 D: OwnCloudClient #1 : Creating OwnCloudClient
2019/06/19 11:16:47 D: OwnCloudClient : Executing PostMethod in request with id 76f69098-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:47 D: OAuth2RefreshAccessTokenOperation : OAUTH2: raw response from POST TOKEN: {"error":"invalid_grant"}
2019/06/19 11:16:47 E: AccountAuthenticator : Failed to refresh access token
2019/06/19 11:16:47 D: OwnCloudClient : Executing GetMethod in request with id 97965865-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:48 E: GetRemoteCapabilitiesOperation : Failed response while getting capabilities from the server 
2019/06/19 11:16:48 E: GetRemoteCapabilitiesOperation : *** status code: 401; response message: {"message":"Current user is not logged in"}
2019/06/19 11:16:48 W: com.owncloud.android.operations.SyncCapabilitiesOperation : Remote capabilities not available
2019/06/19 11:16:48 D: OwnCloudClient : Executing GetMethod in request with id 7d8a499f-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:48 I: GetRemoteStatusOperation : Connection check at https://polybox.ethz.ch: Operation finished with HTTP status code -1 (success)
2019/06/19 11:16:48 D: OwnCloudClient #2 : Creating OwnCloudClient
2019/06/19 11:16:48 V: AccountAuthenticator : Refreshing token!
2019/06/19 11:16:48 D: OwnCloudClient #3 : Creating OwnCloudClient
2019/06/19 11:16:48 D: OwnCloudClient : Executing PostMethod in request with id 5f0b9ab9-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:49 D: OAuth2RefreshAccessTokenOperation : OAUTH2: raw response from POST TOKEN: {"error":"invalid_grant"}
2019/06/19 11:16:49 E: AccountAuthenticator : Failed to refresh access token
2019/06/19 11:16:49 D: OwnCloudClient : Executing GetMethod in request with id 5b56858d-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:50 V: AccountAuthenticator : Refreshing token!
2019/06/19 11:16:50 D: OwnCloudClient #4 : Creating OwnCloudClient
2019/06/19 11:16:50 D: OwnCloudClient : Executing PostMethod in request with id 87bd1e79-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:51 D: OAuth2RefreshAccessTokenOperation : OAUTH2: raw response from POST TOKEN: {"error":"invalid_grant"}
2019/06/19 11:16:51 E: AccountAuthenticator : Failed to refresh access token
2019/06/19 11:16:51 D: OwnCloudClient : Executing GetMethod in request with id 190b841b-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:51 E: GetRemoteUserInfoOperation : Failed response while getting user information 
2019/06/19 11:16:51 E: GetRemoteUserInfoOperation : *** status code: 401 ; response message: {"message":"Current user is not logged in"}
2019/06/19 11:16:51 W: RefreshFolderOperation : Couldn't update user profile from server
2019/06/19 11:16:51 D: SynchronizeFolderOperation : Fetching list of files in  [email protected]/, if changed
2019/06/19 11:16:51 D: OwnCloudClient : Executing PropfindMethod in request with id 5e1d4b4f-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:51 V: AccountAuthenticator : Refreshing token!
2019/06/19 11:16:51 D: OwnCloudClient #5 : Creating OwnCloudClient
2019/06/19 11:16:51 D: OwnCloudClient : Executing PostMethod in request with id ea4dbb23-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:52 D: OAuth2RefreshAccessTokenOperation : OAUTH2: raw response from POST TOKEN: {"error":"invalid_grant"}
2019/06/19 11:16:52 E: AccountAuthenticator : Failed to refresh access token
2019/06/19 11:16:52 D: OwnCloudClient : Executing PropfindMethod in request with id fb0d3900-xxxx-xxxx-xxxx-xxxxxxxxxxxx
2019/06/19 11:16:52 E: ReadRemoteFolderOperation : Synchronized /: Operation finished with HTTP status code 401 (fail)
2019/06/19 11:16:52 E: SynchronizeFolderOperation : Checked [email protected]/ : Operation finished with HTTP status code 401 (fail)
2019/06/19 11:16:52 D: RefreshFolderOperation : Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_CONTENTS_SYNCED
2019/06/19 11:16:52 D: RefreshFolderOperation : Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_SHARES_SYNCED
2019/06/19 11:16:52 D: AccountUtils : Saving Cookies: 508fc14d93fa7=84xxxxxxxxxxxxxxxxxxxxxxxxxxxxxx; path=/; secure; httponly;oc_sessionPassphrase=cqpwxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx; path=/; secure; httponly;r"

They seem to be similar to those I already grabbed from logcat

[jesmrec]

Token refreshing is failing, causing a 401 response from the server. Tokens expire every hour and a new one is granted (this is the expected behaviour). Server logs also would help to know why the refresh token is rejected. Would it be posible to get them?

[michaelstingl]

@thgoebel The ownCloud admins should be able to grep their logs for the request id's (X-REQUEST-ID). /cc @caratsch

[caratsch]

ssl_access_log:

178.197.232.186 - - [19/Jun/2019:11:16:52 +0200] "PROPFIND /remote.php/dav/files/thgoebel/ HTTP/1.1" 401 557 "-" "Mozilla/5.0 (Android) ownCloud-android/2.11.0"
178.197.232.186 - - [19/Jun/2019:11:16:51 +0200] "PROPFIND /remote.php/dav/files/thgoebel/ HTTP/1.1" 401 557 "-" "Mozilla/5.0 (Android) ownCloud-android/2.11.0"

OwnCloud log (same timestamp as ssl_access_log):

{"reqId":"87bd1e79-34cf-492c-8db6-7b0d18431213","level":3,"time":"2019-06-19T11:16:51+02:00","remoteAddr":"129.132.71.241","user":"--","app":"OCA\\OAuth2\\Controller\\OAuthApiController","method":"POST","url":"\/index.php\/apps\/oauth2\/api\/v1\/token","message":"Exception: {\"Exception\":\"OCP\\\\AppFramework\\\\Db\\\\DoesNotExistException\",\"Message\":\"Did expect one result but found none when executing: query \\\"SELECT * FROM `*PREFIX*oauth2_refresh_tokens` WHERE `token` = ?\\\"; parameters Array\\n(\\n    [0] => WuUp2RmvDOhGAsySQe2Dysa3GQ2AC2Wy8zLfqksZuYkaZ6atQc39yZHfr3Qur1Hp\\n)\\n; limit \\\"\\\"; offset \\\"\\\"\",\"Code\":0,\"Trace\":\"#0 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php(363): OCP\\\\AppFramework\\\\Db\\\\Mapper->findOneQuery('SELECT * FROM `...', Array, NULL, NULL)\\n#1 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Db\\\/RefreshTokenMapper.php(75): OCP\\\\AppFramework\\\\Db\\\\Mapper->findEntity('SELECT * FROM `...', Array, NULL, NULL)\\n#2 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Controller\\\/OAuthApiController.php(171): OCA\\\\OAuth2\\\\Db\\\\RefreshTokenMapper->findByToken('WuUp2RmvDOhGAsy...')\\n#3 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(153): OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController->generateToken(*** sensitive parameters replaced ***)\\n#4 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(85): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#5 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(100): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#6 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(46): OC\\\\AppFramework\\\\App::main('OCA\\\\\\\\OAuth2\\\\\\\\Cont...', 'generateToken', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#7 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/Route\\\/Router.php(342): OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#8 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/base.php(909): OC\\\\Route\\\\Router->match('\\\/apps\\\/oauth2\\\/ap...')\\n#9 \\\/var\\\/www\\\/html\\\/owncloud\\\/index.php(54): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php\",\"Line\":281}"}
{"reqId":"22eb9264-71f1-4d9e-aecc-6b8890e49df9","level":3,"time":"2019-06-19T11:16:51+02:00","remoteAddr":"129.132.71.241","user":"--","app":"OCA\\OAuth2\\Controller\\OAuthApiController","method":"POST","url":"\/index.php\/apps\/oauth2\/api\/v1\/token","message":"Exception: {\"Exception\":\"OCP\\\\AppFramework\\\\Db\\\\DoesNotExistException\",\"Message\":\"Did expect one result but found none when executing: query \\\"SELECT * FROM `*PREFIX*oauth2_refresh_tokens` WHERE `token` = ?\\\"; parameters Array\\n(\\n    [0] => jnw9k7154mK7hYsyFXnYQFCVt4cpyO3lYGP8dEe2tY9aBmadIKH2N9CwlgSwiPDk\\n)\\n; limit \\\"\\\"; offset \\\"\\\"\",\"Code\":0,\"Trace\":\"#0 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php(363): OCP\\\\AppFramework\\\\Db\\\\Mapper->findOneQuery('SELECT * FROM `...', Array, NULL, NULL)\\n#1 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Db\\\/RefreshTokenMapper.php(75): OCP\\\\AppFramework\\\\Db\\\\Mapper->findEntity('SELECT * FROM `...', Array, NULL, NULL)\\n#2 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Controller\\\/OAuthApiController.php(171): OCA\\\\OAuth2\\\\Db\\\\RefreshTokenMapper->findByToken('jnw9k7154mK7hYs...')\\n#3 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(153): OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController->generateToken(*** sensitive parameters replaced ***)\\n#4 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(85): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#5 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(100): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#6 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(46): OC\\\\AppFramework\\\\App::main('OCA\\\\\\\\OAuth2\\\\\\\\Cont...', 'generateToken', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#7 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/Route\\\/Router.php(342): OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#8 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/base.php(909): OC\\\\Route\\\\Router->match('\\\/apps\\\/oauth2\\\/ap...')\\n#9 \\\/var\\\/www\\\/html\\\/owncloud\\\/index.php(54): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php\",\"Line\":281}"}
{"reqId":"XQn9gzy@kn535zcXXzpUewAAACY","level":1,"time":"2019-06-19T11:16:51+02:00","remoteAddr":"129.132.71.241","user":"--","app":"admin_audit","method":"PROPFIND","url":"\/remote.php\/webdav\/power_point_talks\/2013\/ParisFlagshipGraphene","message":"User tihn attempted to log into polybox from IP address: 129.132.71.241 [CLIENT_IP: 129.132.71.241] [USER_AGENT: unknown]"}
{"reqId":"XQn9gmyGcYWUiumod1TE@AAAABI","level":1,"time":"2019-06-19T11:16:51+02:00","remoteAddr":"129.132.71.241","user":"cbonjour","app":"admin_audit","method":"PROPFIND","url":"\/remote.php\/webdav\/Enpass\/vault.enpassdbsync","message":"User cbonjour logged into polybox from IP address: 129.132.71.241 [CLIENT_IP: 129.132.71.241] [USER_AGENT: Mozilla\/5.0]"}
{"reqId":"XQn9gzy@kn535zcXXzpUewAAACY","level":1,"time":"2019-06-19T11:16:51+02:00","remoteAddr":"129.132.71.241","user":"tihn","app":"admin_audit","method":"PROPFIND","url":"\/remote.php\/webdav\/power_point_talks\/2013\/ParisFlagshipGraphene","message":"User tihn logged into polybox from IP address: 129.132.71.241 [CLIENT_IP: 129.132.71.241] [USER_AGENT: unknown]"}
{"reqId":"bd126492-8fc1-456b-90c7-2b53b5a2a165","level":3,"time":"2019-06-19T11:16:51+02:00","remoteAddr":"129.132.71.241","user":"--","app":"OCA\\OAuth2\\Controller\\OAuthApiController","method":"POST","url":"\/index.php\/apps\/oauth2\/api\/v1\/token","message":"Exception: {\"Exception\":\"OCP\\\\AppFramework\\\\Db\\\\DoesNotExistException\",\"Message\":\"Did expect one result but found none when executing: query \\\"SELECT * FROM `*PREFIX*oauth2_refresh_tokens` WHERE `token` = ?\\\"; parameters Array\\n(\\n    [0] => jnw9k7154mK7hYsyFXnYQFCVt4cpyO3lYGP8dEe2tY9aBmadIKH2N9CwlgSwiPDk\\n)\\n; limit \\\"\\\"; offset \\\"\\\"\",\"Code\":0,\"Trace\":\"#0 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php(363): OCP\\\\AppFramework\\\\Db\\\\Mapper->findOneQuery('SELECT * FROM `...', Array, NULL, NULL)\\n#1 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Db\\\/RefreshTokenMapper.php(75): OCP\\\\AppFramework\\\\Db\\\\Mapper->findEntity('SELECT * FROM `...', Array, NULL, NULL)\\n#2 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Controller\\\/OAuthApiController.php(171): OCA\\\\OAuth2\\\\Db\\\\RefreshTokenMapper->findByToken('jnw9k7154mK7hYs...')\\n#3 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(153): OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController->generateToken(*** sensitive parameters replaced ***)\\n#4 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(85): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#5 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(100): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#6 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(46): OC\\\\AppFramework\\\\App::main('OCA\\\\\\\\OAuth2\\\\\\\\Cont...', 'generateToken', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#7 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/Route\\\/Router.php(342): OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#8 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/base.php(909): OC\\\\Route\\\\Router->match('\\\/apps\\\/oauth2\\\/ap...')\\n#9 \\\/var\\\/www\\\/html\\\/owncloud\\\/index.php(54): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php\",\"Line\":281}"}
{"reqId":"3913285f-43df-448c-8ba5-f61de94446a8","level":3,"time":"2019-06-19T11:16:52+02:00","remoteAddr":"129.132.71.241","user":"--","app":"OCA\\OAuth2\\Controller\\OAuthApiController","method":"POST","url":"\/index.php\/apps\/oauth2\/api\/v1\/token","message":"Exception: {\"Exception\":\"OCP\\\\AppFramework\\\\Db\\\\DoesNotExistException\",\"Message\":\"Did expect one result but found none when executing: query \\\"SELECT * FROM `*PREFIX*oauth2_refresh_tokens` WHERE `token` = ?\\\"; parameters Array\\n(\\n    [0] => jnw9k7154mK7hYsyFXnYQFCVt4cpyO3lYGP8dEe2tY9aBmadIKH2N9CwlgSwiPDk\\n)\\n; limit \\\"\\\"; offset \\\"\\\"\",\"Code\":0,\"Trace\":\"#0 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php(363): OCP\\\\AppFramework\\\\Db\\\\Mapper->findOneQuery('SELECT * FROM `...', Array, NULL, NULL)\\n#1 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Db\\\/RefreshTokenMapper.php(75): OCP\\\\AppFramework\\\\Db\\\\Mapper->findEntity('SELECT * FROM `...', Array, NULL, NULL)\\n#2 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Controller\\\/OAuthApiController.php(171): OCA\\\\OAuth2\\\\Db\\\\RefreshTokenMapper->findByToken('jnw9k7154mK7hYs...')\\n#3 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(153): OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController->generateToken(*** sensitive parameters replaced ***)\\n#4 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(85): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#5 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(100): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#6 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(46): OC\\\\AppFramework\\\\App::main('OCA\\\\\\\\OAuth2\\\\\\\\Cont...', 'generateToken', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#7 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/Route\\\/Router.php(342): OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#8 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/base.php(909): OC\\\\Route\\\\Router->match('\\\/apps\\\/oauth2\\\/ap...')\\n#9 \\\/var\\\/www\\\/html\\\/owncloud\\\/index.php(54): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php\",\"Line\":281}"}
{"reqId":"XQn9hMM9tH2QMvj4UUqLOgAAACY","level":1,"time":"2019-06-19T11:16:52+02:00","remoteAddr":"129.132.71.241","user":"--","app":"admin_audit","method":"PROPFIND","url":"\/remote.php\/webdav\/power_point_talks\/2013\/TriesteApr2013","message":"User tihn attempted to log into polybox from IP address: 129.132.71.241 [CLIENT_IP: 129.132.71.241] [USER_AGENT: unknown]"}
{"reqId":"XQn9hMM9tH2QMvj4UUqLOgAAACY","level":1,"time":"2019-06-19T11:16:52+02:00","remoteAddr":"129.132.71.241","user":"tihn","app":"admin_audit","method":"PROPFIND","url":"\/remote.php\/webdav\/power_point_talks\/2013\/TriesteApr2013","message":"User tihn logged into polybox from IP address: 129.132.71.241 [CLIENT_IP: 129.132.71.241] [USER_AGENT: unknown]"}
{"reqId":"c917fa03-a2db-4a26-80af-ce4d0ad57697","level":3,"time":"2019-06-19T11:16:52+02:00","remoteAddr":"129.132.71.241","user":"--","app":"OCA\\OAuth2\\Controller\\OAuthApiController","method":"POST","url":"\/index.php\/apps\/oauth2\/api\/v1\/token","message":"Exception: {\"Exception\":\"OCP\\\\AppFramework\\\\Db\\\\DoesNotExistException\",\"Message\":\"Did expect one result but found none when executing: query \\\"SELECT * FROM `*PREFIX*oauth2_refresh_tokens` WHERE `token` = ?\\\"; parameters Array\\n(\\n    [0] => jnw9k7154mK7hYsyFXnYQFCVt4cpyO3lYGP8dEe2tY9aBmadIKH2N9CwlgSwiPDk\\n)\\n; limit \\\"\\\"; offset \\\"\\\"\",\"Code\":0,\"Trace\":\"#0 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php(363): OCP\\\\AppFramework\\\\Db\\\\Mapper->findOneQuery('SELECT * FROM `...', Array, NULL, NULL)\\n#1 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Db\\\/RefreshTokenMapper.php(75): OCP\\\\AppFramework\\\\Db\\\\Mapper->findEntity('SELECT * FROM `...', Array, NULL, NULL)\\n#2 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Controller\\\/OAuthApiController.php(171): OCA\\\\OAuth2\\\\Db\\\\RefreshTokenMapper->findByToken('jnw9k7154mK7hYs...')\\n#3 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(153): OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController->generateToken(*** sensitive parameters replaced ***)\\n#4 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(85): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#5 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(100): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#6 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(46): OC\\\\AppFramework\\\\App::main('OCA\\\\\\\\OAuth2\\\\\\\\Cont...', 'generateToken', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#7 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/Route\\\/Router.php(342): OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#8 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/base.php(909): OC\\\\Route\\\\Router->match('\\\/apps\\\/oauth2\\\/ap...')\\n#9 \\\/var\\\/www\\\/html\\\/owncloud\\\/index.php(54): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php\",\"Line\":281}"}
{"reqId":"ee33c4d7-6304-47a6-90ab-470ec7ccff2a","level":3,"time":"2019-06-19T11:16:52+02:00","remoteAddr":"129.132.71.241","user":"--","app":"OCA\\OAuth2\\Controller\\OAuthApiController","method":"POST","url":"\/index.php\/apps\/oauth2\/api\/v1\/token","message":"Exception: {\"Exception\":\"OCP\\\\AppFramework\\\\Db\\\\DoesNotExistException\",\"Message\":\"Did expect one result but found none when executing: query \\\"SELECT * FROM `*PREFIX*oauth2_refresh_tokens` WHERE `token` = ?\\\"; parameters Array\\n(\\n    [0] => ASyHikZc5Lw1anx3cwaQB2SmRb3qyAV5PnjaZcu6yDE52UeN3p9dzJq2ovxdktPq\\n)\\n; limit \\\"\\\"; offset \\\"\\\"\",\"Code\":0,\"Trace\":\"#0 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php(363): OCP\\\\AppFramework\\\\Db\\\\Mapper->findOneQuery('SELECT * FROM `...', Array, NULL, NULL)\\n#1 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Db\\\/RefreshTokenMapper.php(75): OCP\\\\AppFramework\\\\Db\\\\Mapper->findEntity('SELECT * FROM `...', Array, NULL, NULL)\\n#2 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Controller\\\/OAuthApiController.php(171): OCA\\\\OAuth2\\\\Db\\\\RefreshTokenMapper->findByToken('ASyHikZc5Lw1anx...')\\n#3 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(153): OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController->generateToken(*** sensitive parameters replaced ***)\\n#4 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(85): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#5 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(100): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#6 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(46): OC\\\\AppFramework\\\\App::main('OCA\\\\\\\\OAuth2\\\\\\\\Cont...', 'generateToken', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#7 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/Route\\\/Router.php(342): OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#8 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/base.php(909): OC\\\\Route\\\\Router->match('\\\/apps\\\/oauth2\\\/ap...')\\n#9 \\\/var\\\/www\\\/html\\\/owncloud\\\/index.php(54): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php\",\"Line\":281}"}
{"reqId":"ea4dbb23-a867-4b49-a621-f3950fa0392e","level":3,"time":"2019-06-19T11:16:52+02:00","remoteAddr":"129.132.71.241","user":"--","app":"OCA\\OAuth2\\Controller\\OAuthApiController","method":"POST","url":"\/index.php\/apps\/oauth2\/api\/v1\/token","message":"Exception: {\"Exception\":\"OCP\\\\AppFramework\\\\Db\\\\DoesNotExistException\",\"Message\":\"Did expect one result but found none when executing: query \\\"SELECT * FROM `*PREFIX*oauth2_refresh_tokens` WHERE `token` = ?\\\"; parameters Array\\n(\\n    [0] => WuUp2RmvDOhGAsySQe2Dysa3GQ2AC2Wy8zLfqksZuYkaZ6atQc39yZHfr3Qur1Hp\\n)\\n; limit \\\"\\\"; offset \\\"\\\"\",\"Code\":0,\"Trace\":\"#0 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php(363): OCP\\\\AppFramework\\\\Db\\\\Mapper->findOneQuery('SELECT * FROM `...', Array, NULL, NULL)\\n#1 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Db\\\/RefreshTokenMapper.php(75): OCP\\\\AppFramework\\\\Db\\\\Mapper->findEntity('SELECT * FROM `...', Array, NULL, NULL)\\n#2 \\\/var\\\/www\\\/html\\\/owncloud\\\/apps\\\/oauth2\\\/lib\\\/Controller\\\/OAuthApiController.php(171): OCA\\\\OAuth2\\\\Db\\\\RefreshTokenMapper->findByToken('WuUp2RmvDOhGAsy...')\\n#3 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(153): OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController->generateToken(*** sensitive parameters replaced ***)\\n#4 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Http\\\/Dispatcher.php(85): OC\\\\AppFramework\\\\Http\\\\Dispatcher->executeController(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#5 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/App.php(100): OC\\\\AppFramework\\\\Http\\\\Dispatcher->dispatch(Object(OCA\\\\OAuth2\\\\Controller\\\\OAuthApiController), 'generateToken')\\n#6 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/AppFramework\\\/Routing\\\/RouteActionHandler.php(46): OC\\\\AppFramework\\\\App::main('OCA\\\\\\\\OAuth2\\\\\\\\Cont...', 'generateToken', Object(OC\\\\AppFramework\\\\DependencyInjection\\\\DIContainer), Array)\\n#7 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/private\\\/Route\\\/Router.php(342): OC\\\\AppFramework\\\\Routing\\\\RouteActionHandler->__invoke(Array)\\n#8 \\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/base.php(909): OC\\\\Route\\\\Router->match('\\\/apps\\\/oauth2\\\/ap...')\\n#9 \\\/var\\\/www\\\/html\\\/owncloud\\\/index.php(54): OC::handleRequest()\\n#10 {main}\",\"File\":\"\\\/var\\\/www\\\/html\\\/owncloud\\\/lib\\\/public\\\/AppFramework\\\/Db\\\/Mapper.php\",\"Line\":281}"}

Let me know if you need more!

[thgoebel]

It has been working again lately, even with the latest v2.12. No clue why, but since I now feel that it's more likely to have been a server issue I'm closing this.