continuous joined/left call

[shylaev]

General Description

I am sorry for large listing
Talked server and NextCloud are separate hosts

How To Reproduce?

get fresh install

1. Ubuntu 20.04 LTS up-to-date on hyper-v
2. Talked server cloned by git and installed as pointed in ReadMe
   by the way geckodriver is not in repos for present time, it was installed manually
3. NextCloud Talked App was configured in according with manual on NextCloud Apps project's site
4. Create Chat allowing guest
5. Start conversation
6. type /recording start in chat of conversation

Screenshots from NextCloud 24.0.4 Talk 14.0.4

https://ibb.co/6WWKMjS

Logs of httpd_access of NextCloud (Apache/2.4.51) 10.222.10.55 is Talked Server host

10.222.10.55 - - [21/Sep/2022:08:58:26 +0300] "DELETE /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 81 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:16 +0300] "GET /ocs/v2.php/apps/spreed/api/v1/chat/fmrnh4x8?setReadMarker=0&lookIntoFuture=1&lastKnownMessageId=16740&includeLastKnown=0 HTTP/1.1" 200 301 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:27 +0300] "POST /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 81 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:27 +0300] "GET /ocs/v2.php/apps/spreed/api/v1/chat/fmrnh4x8?setReadMarker=0&lookIntoFuture=1&lastKnownMessageId=16742&includeLastKnown=0 HTTP/1.1" 200 304 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:28 +0300] "GET /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 701 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:30 +0300] "GET /ocs/v2.php/apps/spreed/api/v4/room/fmrnh4x8 HTTP/1.1" 200 839 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:38 +0300] "DELETE /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 81 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:38 +0300] "POST /ocs/v2.php/apps/spreed/api/v4/room/fmrnh4x8/participants/active HTTP/1.1" 200 841 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:28 +0300] "GET /ocs/v2.php/apps/spreed/api/v1/chat/fmrnh4x8?setReadMarker=0&lookIntoFuture=1&lastKnownMessageId=16743&includeLastKnown=0 HTTP/1.1" 200 302 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:40 +0300] "GET /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 436 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:40 +0300] "POST /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 81 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:40 +0300] "GET /ocs/v2.php/apps/spreed/api/v1/chat/fmrnh4x8?setReadMarker=0&lookIntoFuture=1&lastKnownMessageId=16744&includeLastKnown=0 HTTP/1.1" 200 303 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:41 +0300] "GET /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 699 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:43 +0300] "GET /ocs/v2.php/apps/spreed/api/v4/room/fmrnh4x8 HTTP/1.1" 200 838 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:43 +0300] "GET /ocs/v2.php/apps/spreed/api/v4/room/fmrnh4x8 HTTP/1.1" 200 838 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:51 +0300] "DELETE /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 81 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:51 +0300] "POST /ocs/v2.php/apps/spreed/api/v4/room/fmrnh4x8/participants/active HTTP/1.1" 200 838 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:42 +0300] "GET /ocs/v2.php/apps/spreed/api/v1/chat/fmrnh4x8?setReadMarker=0&lookIntoFuture=1&lastKnownMessageId=16745&includeLastKnown=0 HTTP/1.1" 200 301 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:52 +0300] "POST /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 81 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:52 +0300] "GET /ocs/v2.php/apps/spreed/api/v1/chat/fmrnh4x8?setReadMarker=0&lookIntoFuture=1&lastKnownMessageId=16746&includeLastKnown=0 HTTP/1.1" 200 304 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:54 +0300] "GET /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 697 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:58:55 +0300] "GET /ocs/v2.php/apps/spreed/api/v4/room/fmrnh4x8 HTTP/1.1" 200 837 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0"
10.222.10.55 - - [21/Sep/2022:08:59:04 +0300] "DELETE /ocs/v2.php/apps/spreed/api/v4/call/fmrnh4x8 HTTP/1.1" 200 81 "-" "Mozilla/5.0 (X11; Linux x86_64; rv:91.0) Gecko/20100101 Firefox/91.0

no suspicious logs on Talked server side even in debug mode. (10.222.10.71 is NextCloud host) something like this

DEBUG:selenium.webdriver.remote.remote_connection:Remote response: status=200 | data={"value":null} | headers=HTTPHeaderDict({'content-type': 'application/json; charset=utf-8', 'cache-control': 'no-cache', 'content-length': '14', 'date': 'Wed, 21 Sep 2022 06:12:04 GMT'})
DEBUG:selenium.webdriver.remote.remote_connection:Finished Request
INFO:root:Starting ffmpeg process
INFO:root:Recording has started
[pid: 14236|app: 0|req: 1/1] 10.222.10.71 () {32 vars in 359 bytes} [Wed Sep 21 09:11:49 2022] POST /start => generated 37 bytes in 19692 msecs (HTTP/1.1 200) 2 headers in 71 bytes (1 switches on core 0)
[x11grab @ 0x55eaef1f19c0] Stream #0: not enough frames to estimate rate; consider increasing probesize
INFO:root:Stop ffmpeg process
INFO:root:Stop browser
DEBUG:selenium.webdriver.remote.remote_connection:DELETE http://localhost:46841/session/0c005eb8-ab4c-4859-90a1-b1518b77b756/window {}

how to turn on debug selenium/firefox to get logs?
how to fix it

[mwalbeck]

Hi, inside folder where talked is installed you should find a geckodriver.log file, which contains logs from the browser. There might be some relevant info in there.

[shylaev]

Hi, thanks for attention
here is the geckodriver.log during call with joined/left
1664174092642 mozrunner::runner INFO Running command: "/usr/bin/firefox" "--marionette" "--kiosk" "--width=1280" "--height=720" "--remote-debugging-port" "53739" "--remote-allow-hosts" "localhost" "-no-remote" "-profile" "/tmp/rust_mozprofileCUOh92"
[GFX1-]: glxtest: libEGL missing
1664174093200 Marionette INFO Marionette enabled
console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/rust_mozprofileCUOh92/search.json.mozlz4", (void 0)))
DevTools listening on ws://localhost:53739/devtools/browser/7299dbff-31d5-4efa-9cfc-0a6c5af5a86c
1664174094952 Marionette INFO Listening on port 42873
1664174095074 RemoteAgent WARN TLS certificate errors will be ignored for this session

from this point I'm seeing numerous attempts join and left chatroom.
and this is the end of records

JavaScript error: resource://gre/actors/AutoCompleteChild.jsm, line 125: Error: Invalid autocomplete selectedIndex
1664174157913 Marionette INFO Stopped listening on port 42873
JavaScript error: resource:///modules/Interactions.jsm, line 209: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
Exiting due to channel error.
Exiting due to channel error.

that's all
was it helpful?