search

threema-ch / threema-web

The Threema Web application.
GNU Affero General Public License v3.0
1k stars 107 forks source link

Threema app displays session is still active although already disconnected #951

Closed ovalseven8 closed 4 years ago

ovalseven8 commented 4 years ago

Bug Description

Threema session is marked as active although disconnected in Threema Web. It takes ~ 16 seconds on my phone until the right state is displayed.

Not sure if it's only a UI bug or there is a logical implementation error.

Screenshot_20191223-170321

Screenshot_20191223-135857

Steps to Reproduce (for bugs)

  1. Go to https://web.threema.ch
  2. Connect to session
  3. Close session in the app
  4. Session is marked as inactive on the smartphone :heavy_check_mark:
  1. Go to https://web.threema.ch
  2. Connect to session
  3. Close session in Threema Web
  4. It takes ~ 16 seconds until marked as inactive on the smartphone ❌

Your Environment

dbrgn commented 4 years ago

With "Close session in Threema Web" you mean going through the menu and clicking "Close Session", and not just pressing F5, right?

ovalseven8 commented 4 years ago

With "Close session in Threema Web" you mean going through the menu and clicking "Close Session", and not just pressing F5, right?

Exactly.

Here some logs. At ~ 17:47:20 I closed the session in Threema Web. At ~ 17:47:36 the app recognized the session is no longer active.

Threema Web logs ``` { "config": { "VERSION": "2.3.1", "SELF_HOSTED": false, "VERSION_MOUNTAIN": "Säntis", "VERSION_MOUNTAIN_URL": "https://en.wikipedia.org/wiki/Säntis", "VERSION_MOUNTAIN_IMAGE_URL": "https://en.wikipedia.org/wiki/S%C3%A4ntis#/media/File:S%C3%A4ntis_mountain_by_sunset..JPG", "VERSION_MOUNTAIN_IMAGE_COPYRIGHT": "CC BY-SA 3.0 B0rder", "VERSION_MOUNTAIN_HEIGHT": 2502, "PREV_PROTOCOL_LAST_VERSION": "1.8.2", "GIT_BRANCH": "master", "SALTYRTC_HOST": "saltyrtc-{prefix}.threema.ch", "SALTYRTC_PORT": 443, "SALTYRTC_SERVER_KEY": "b1337fc8402f7db8ea639e05ed05d65463e24809792f91eca29e88101b4a2171", "ICE_SERVERS": [ { "urls": [ "turn:turn-{prefix}.threema.ch:443?transport=udp", "turn:turn-{prefix}.threema.ch:443?transport=tcp", "turns:turn-{prefix}.threema.ch:443" ], "username": "[String]", "credential": "[String]" } ], "PUSH_URL": "https://push-web.threema.ch/push", "LOG_TAG_PADDING": 20, "CONSOLE_LOG_LEVEL": "info", "REPORT_LOG_LEVEL": "debug", "REPORT_LOG_LIMIT": 1000, "COMPOSE_AREA_LOG_LEVEL": "warn", "SALTYRTC_LOG_LEVEL": "warn", "TIMER_LOG_LEVEL": "info", "ARP_LOG_LEVEL": "debug", "ARP_LOG_TRACE": false, "MSGPACK_LOG_TRACE": false, "TRANSPORT_LOG_LEVEL": "warn", "VISUALIZE_STATE": false }, "browser": "Firefox 71", "log": [ [ 1577119585001, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577119585011, "debug", "%c[Theme-S]", "color: #fff; background-color: #cc9900", "Initializing with theme threema" ], [ 1577119585012, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119585090, "debug", "[Welcome-C]", "Detected browser:", "Firefox 71" ], [ 1577119585090, "debug", "[Settings-S]", "Removing settings key:", "v2infoShown" ], [ 1577119585093, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119585093, "info", "[Welcome-C]", "Initialize session by scanning QR code..." ], [ 1577119585093, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=false, close=welcome, connectionBuildupState=new)" ], [ 1577119585093, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577119585093, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577119585093, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119585094, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577119585094, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection was null" ], [ 1577119585096, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing (keyStore=no, peerTrustedKey=no, \n resume=false)" ], [ 1577119585096, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119585096, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Discarded previous session" ], [ 1577119585119, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Public key:", "4c659d517367db6acce85a6065cca9532bea17d20c5fa4a66f9e65642de25a7d" ], [ 1577119585119, "debug", "[Welcome-C]", "Checking if the session is already open in another tab or window" ], [ 1577119585132, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Starting WebClientService..." ], [ 1577119585132, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Creating new startup promise" ], [ 1577119585134, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "new", "=>", "connecting" ], [ 1577119585134, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "new" ], [ 1577119585134, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "ws-connecting" ], [ 1577119585208, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: error -> warning (attempt=0)" ], [ 1577119585219, "info", "[Version-S]", "Using Threema Web version", "2.3.1" ], [ 1577119585438, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "ws-connecting", "=>", "server-handshake" ], [ 1577119585520, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "connecting", "=>", "waiting" ], [ 1577119585520, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "server-handshake", "=>", "peer-handshake" ], [ 1577119607863, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "waiting", "=>", "peer_handshake" ], [ 1577119607864, "info", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Initialize WebRTC PeerConnection" ], [ 1577119607864, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE servers used:", [ "turn:turn-e6.threema.ch:443?transport=udp", "turn:turn-e6.threema.ch:443?transport=tcp", "turns:turn-e6.threema.ch:443" ] ], [ 1577119607875, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Setting up ICE candidate handling" ], [ 1577119607878, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "peer-handshake", "=>", "task" ], [ 1577119607879, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "RTCPeerConnection: negotiation needed" ], [ 1577119607928, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signaling state change:", "have-local-offer" ], [ 1577119607928, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Created offer, set local description" ], [ 1577119607944, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE gathering state change:", "gathering" ], [ 1577119607945, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Gathered local ICE candidate:", "candidate:0 1 UDP 2122252543 2003:*:*:*:*:*:*:* 39825 typ host" ], [ 1577119607945, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Gathered local ICE candidate:", "candidate:3 1 TCP 2105524479 2003:*:*:*:*:*:*:* 9 typ host tcptype active" ], [ 1577119607994, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE gathering state change:", "complete" ], [ 1577119607994, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "No more local ICE candidates" ], [ 1577119608301, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signaling state change:", "stable" ], [ 1577119608303, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Received answer, set remote description" ], [ 1577119608303, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Initiator flow done" ], [ 1577119608304, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE connection state change:", "checking" ], [ 1577119608304, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Task connection state:", "new", "=>", "connecting" ], [ 1577119608305, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Connection state change:", "connecting" ], [ 1577119608305, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE connection state change:", "connected" ], [ 1577119608307, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Task connection state:", "connecting", "=>", "connected" ], [ 1577119608307, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Connection state change:", "connected" ], [ 1577119608308, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: warning -> ok (attempt=0)" ], [ 1577119608335, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Adding remote ICE candidate:", "candidate:2957806663 1 UDP 2122262783 2003:*:*:*:*:*:*:* 34251 typ host ufrag y2jf" ], [ 1577119608357, "info", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signalling data channel open" ], [ 1577119608372, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Adding remote ICE candidate:", "candidate:3455323002 1 UDP 2122194687 192.168.*.* 36075 typ host ufrag y2jf" ], [ 1577119608409, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Handover done" ], [ 1577119608409, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing NotificationService..." ], [ 1577119608409, "debug", "[Notification-S]", "Notification API available:", true ], [ 1577119608409, "debug", "[Notification-S]", "Initial notificationPermission", false ], [ 1577119608409, "debug", "[Notification-S]", "Fetching settings..." ], [ 1577119608409, "debug", "[Settings-S]", "Retrieving settings key:", "notifications" ], [ 1577119608409, "debug", "[Settings-S]", "Retrieving settings key:", "notificationPreview" ], [ 1577119608410, "debug", "[Settings-S]", "Retrieving settings key:", "notificationSound" ], [ 1577119608410, "debug", "[Notification-S]", "Desktop notifications:", "", "Asking user..." ], [ 1577119608410, "debug", "[Notification-S]", "Requesting notification permission..." ], [ 1577119608410, "debug", "[Notification-S]", "Notification preview:", "true", "Using default value (true)" ], [ 1577119608410, "debug", "[Settings-S]", "Storing settings key:", "notificationPreview" ], [ 1577119608410, "debug", "[Notification-S]", "Notification sound:", "false", "Using default value (false)" ], [ 1577119608410, "debug", "[Settings-S]", "Storing settings key:", "notificationSound" ], [ 1577119608410, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Creating data channel THREEMA" ], [ 1577119608414, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Data channel THREEMA open" ], [ 1577119608414, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Using chunk length: 65536 for data channel THREEMA" ], [ 1577119608414, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending connection info (resume=no)" ], [ 1577119608415, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "update/connectionInfo", "message" ], [ 1577119608418, "debug", "[Notification-S]", "Notification permission", false ], [ 1577119608450, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (79 bytes)" ], [ 1577119608450, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received update/connectionInfo message" ], [ 1577119608451, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received connectionInfo from device" ], [ 1577119608451, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received connection info (resume=no, sn-out=n/a)" ], [ 1577119608452, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Cannot resume session: Remote did not implement deriving the connection ID" ], [ 1577119608452, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session discarded" ], [ 1577119608452, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119608452, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending client info request" ], [ 1577119608453, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/clientInfo", "message" ], [ 1577119608454, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending profile request" ], [ 1577119608454, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/profile", "message" ], [ 1577119608454, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending receivers request" ], [ 1577119608454, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/receivers", "message" ], [ 1577119608457, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending conversation request" ], [ 1577119608457, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/conversations", "message" ], [ 1577119608457, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending battery status request" ], [ 1577119608457, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/batteryStatus", "message" ], [ 1577119608458, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Required initialization step", "client info", "not completed, add pending routine" ], [ 1577119608458, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "peer_handshake", "=>", "loading" ], [ 1577119608496, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (437 bytes)" ], [ 1577119608496, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/clientInfo message" ], [ 1577119608496, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received client info response" ], [ 1577119608497, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Client device:", "MotoE2(4G-LTE)" ], [ 1577119608497, "info", "%c[Push-S]", "color: #fff; background-color: #9900ff", "Initialized with", "gcm", "token" ], [ 1577119608497, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"client info\" done" ], [ 1577119608499, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (62 bytes)" ], [ 1577119608499, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received update/batteryStatus message" ], [ 1577119608499, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received battery status" ], [ 1577119608499, "debug", "[BatteryStatus-S]", "Status:", { "percent": 37, "isCharging": false } ], [ 1577119608545, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (4298 bytes)" ], [ 1577119608545, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/profile message" ], [ 1577119608545, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received profile response" ], [ 1577119608545, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"profile\" done" ], [ 1577119608581, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (3511 bytes)" ], [ 1577119608583, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/receivers message" ], [ 1577119608583, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received receivers response" ], [ 1577119608583, "debug", "[Browser-S]", "Browser supports extended locale compare options" ], [ 1577119608587, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"receivers\" done" ], [ 1577119608606, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (8410 bytes)" ], [ 1577119608606, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/conversations message" ], [ 1577119608607, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received conversations response" ], [ 1577119608608, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"conversations\" done" ], [ 1577119608608, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Running routine after initialization \"conversations\" completed" ], [ 1577119608608, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "loading", "=>", "done" ], [ 1577119608608, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119615460, "debug", "[Version-S]", "Checking for version update..." ], [ 1577119640789, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=true, close=welcome, connectionBuildupState=closed)" ], [ 1577119640790, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "update/connectionDisconnect", "message" ], [ 1577119640790, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577119640790, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577119640791, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119640791, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577119640791, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing data channel" ], [ 1577119640791, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing signaling" ], [ 1577119640792, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signalling data channel close request" ], [ 1577119640793, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection closed" ], [ 1577119640815, "debug", "[Welcome-C]", "Detected browser:", "Firefox 71" ], [ 1577119640815, "debug", "[Settings-S]", "Removing settings key:", "v2infoShown" ], [ 1577119640815, "debug", "[Version-S]", "Checking for version update..." ], [ 1577119640816, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119640817, "info", "[Welcome-C]", "Initialize session by scanning QR code..." ], [ 1577119640817, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=false, close=welcome, connectionBuildupState=closed)" ], [ 1577119640817, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577119640817, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577119640817, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119640817, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577119640817, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing data channel" ], [ 1577119640817, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing signaling" ], [ 1577119640818, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection closed" ], [ 1577119640823, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing (keyStore=no, peerTrustedKey=no, \n resume=false)" ], [ 1577119640823, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577119640824, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Discarded previous session" ], [ 1577119640833, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Public key:", "a40462475fd997505a974c6d53d6665783d9ec2414976368cc66019957fb9457" ], [ 1577119640834, "debug", "[Welcome-C]", "Checking if the session is already open in another tab or window" ], [ 1577119640859, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: ok -> error (attempt=0)" ], [ 1577119640873, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Starting WebClientService..." ], [ 1577119640873, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Creating new startup promise" ], [ 1577119640873, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "closed", "=>", "connecting" ], [ 1577119640873, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "new" ], [ 1577119640873, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "ws-connecting" ], [ 1577119640927, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: error -> warning (attempt=0)" ], [ 1577119641179, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "ws-connecting", "=>", "server-handshake" ], [ 1577119641230, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "connecting", "=>", "waiting" ], [ 1577119641230, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "server-handshake", "=>", "peer-handshake" ] ] } ```
App logs ``` Mon Dec 23 17:46:44 GMT+01:00 2019 INFO SessionsActivity: Initiate QR scan Mon Dec 23 17:46:44 GMT+01:00 2019 INFO Bb: releaseConnectionLinger: source = activityPaused, timeout = 60000 Mon Dec 23 17:46:44 GMT+01:00 2019 INFO Bb: Alarm type 1 cancel Mon Dec 23 17:46:44 GMT+01:00 2019 INFO Bb: Alarm type 1 schedule in 59997ms Mon Dec 23 17:46:44 GMT+01:00 2019 INFO c: Screen resolution in current orientation: Point(540, 960) Mon Dec 23 17:46:44 GMT+01:00 2019 INFO c: Camera resolution: Point(960, 540) Mon Dec 23 17:46:44 GMT+01:00 2019 INFO c: Best available preview size: Point(960, 540) Mon Dec 23 17:46:46 GMT+01:00 2019 INFO SessionsActivity: Starting Threema Web session Mon Dec 23 17:46:46 GMT+01:00 2019 INFO r: 0: Initialize SessionInstanceServiceImpl Mon Dec 23 17:46:46 GMT+01:00 2019 INFO Bb: acquireConnection: source = activityResumed, refCount = 1 Mon Dec 23 17:46:46 GMT+01:00 2019 INFO o: 0/NEW: Initializing Mon Dec 23 17:46:46 GMT+01:00 2019 INFO Bb: another connection is already active Mon Dec 23 17:46:46 GMT+01:00 2019 INFO r: 0: Starting Threema Web session Mon Dec 23 17:46:46 GMT+01:00 2019 INFO o: 0/NEW: setConnecting Mon Dec 23 17:46:47 GMT+01:00 2019 INFO o: 0/CONNECTING: Initializing Mon Dec 23 17:46:47 GMT+01:00 2019 INFO B: 0: Session state changed from NEW to CONNECTING Mon Dec 23 17:46:47 GMT+01:00 2019 INFO o: 0/CONNECTING: Enter Mon Dec 23 17:46:47 GMT+01:00 2019 INFO o: 0/CONNECTING: Starting connection 0 of session 0 Mon Dec 23 17:46:47 GMT+01:00 2019 INFO o: 0/CONNECTING: Acquire session resources... Mon Dec 23 17:46:47 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Connecting to SaltyRTC server at saltyrtc-4c.threema.ch:443... Mon Dec 23 17:46:47 GMT+01:00 2019 INFO m: 0: Signaling state changed to WS_CONNECTING Mon Dec 23 17:46:47 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:46:47 GMT+01:00 2019 INFO SaltyRTC.RSignaling: WebSocket connection established Mon Dec 23 17:46:47 GMT+01:00 2019 INFO m: 0: Signaling state changed to SERVER_HANDSHAKE Mon Dec 23 17:46:47 GMT+01:00 2019 INFO m: 0: Signaling state changed to PEER_HANDSHAKE Mon Dec 23 17:46:47 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Server handshake done Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Task v1.webrtc.tasks.saltyrtc.org has been selected Mon Dec 23 17:46:48 GMT+01:00 2019 INFO m: 0: Signaling state changed to TASK Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTING: Handover Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTING: Cancelling connect timeout Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/HANDOVER: Initializing Mon Dec 23 17:46:48 GMT+01:00 2019 INFO B: 0: Session state changed from CONNECTING to HANDOVER Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/HANDOVER: Enter Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/HANDOVER: Starting handover... Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Initialize WebRTC PeerConnection Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Renegotiation needed Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 17:46:48 GMT+01:00 2019 INFO m: 0: Peer connection state changed from NEW to CONNECTING and signaling state = TASK Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Peer handshake done Mon Dec 23 17:46:48 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: offer Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Signaling state change to HAVE_REMOTE_OFFER Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Remote description set Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Created answer Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Signaling state change to STABLE Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: candidates Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Adding peer ICE candidate: candidate:0 1 UDP 2122252543 2003:d8:7714:1a00:bded:dd7f:e341:c7fd 39825 typ host Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Local description set Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: ICE gathering state change to GATHERING Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: ICE connection state change to CHECKING Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Adding peer ICE candidate: candidate:3 1 TCP 2105524479 2003:d8:7714:1a00:bded:dd7f:e341:c7fd 9 typ host tcptype active Mon Dec 23 17:46:48 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CONNECTING to CONNECTING and signaling state = TASK Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Sending local ICE candidate: candidate:2957806663 1 udp 2122262783 2003:d8:7714:1a00:e0cf:e12b:b4f9:903c 34251 typ host generation 0 ufrag y2jf network-id 4 network-cost 10 Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Sending local ICE candidate: candidate:3455323002 1 udp 2122194687 192.168.178.20 36075 typ host generation 0 ufrag y2jf network-id 3 network-cost 10 Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: ICE gathering state change to COMPLETE Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: Added 2 ICE candidate(s) from peer Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: ICE connection state change to CONNECTED Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTED Mon Dec 23 17:46:48 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CONNECTING to CONNECTED and signaling state = TASK Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling open Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Signaling transport created Mon Dec 23 17:46:48 GMT+01:00 2019 INFO p: New data channel: THREEMA Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: handover Mon Dec 23 17:46:48 GMT+01:00 2019 INFO m: 0: Handover done Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: Handover to data channel finished Mon Dec 23 17:46:48 GMT+01:00 2019 INFO m: 0: Data channel THREEMA open Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/HANDOVER: Connected Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/HANDOVER: Cancelling handover timeout Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Initializing Mon Dec 23 17:46:48 GMT+01:00 2019 INFO B: 0: Session state changed from HANDOVER to CONNECTED Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Enter Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending update/connectionInfo to peer Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:46:48 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:46:48 GMT+01:00 2019 INFO j: Received client information request Mon Dec 23 17:46:48 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStarted Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:46:48 GMT+01:00 2019 INFO r: 0: Client info sent after 1684 ms Mon Dec 23 17:46:48 GMT+01:00 2019 INFO r: 0: Registered for profile updates Mon Dec 23 17:46:48 GMT+01:00 2019 INFO ThreemaApplication: SessionAndroidService not running...starting Mon Dec 23 17:46:48 GMT+01:00 2019 INFO ThreemaApplication: sending ACTION_START to SessionAndroidService Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:46:48 GMT+01:00 2019 INFO r: 0: Profile sent after 1728 ms Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SessionAndroidService: startForeground called Mon Dec 23 17:46:48 GMT+01:00 2019 INFO SessionAndroidService: ACTION_START Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:46:48 GMT+01:00 2019 INFO r: 0: Receivers sent after 1760 ms Mon Dec 23 17:46:48 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:46:48 GMT+01:00 2019 INFO r: 0: Conversations sent after 1776 ms Mon Dec 23 17:47:23 GMT+01:00 2019 INFO Bb: releaseConnectionLinger: source = activityPaused, timeout = 60000 Mon Dec 23 17:47:23 GMT+01:00 2019 INFO Bb: Alarm type 1 cancel Mon Dec 23 17:47:23 GMT+01:00 2019 INFO Bb: Alarm type 1 schedule in 59977ms Mon Dec 23 17:47:24 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now paused Mon Dec 23 17:47:24 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now hidden Mon Dec 23 17:47:24 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now visible Mon Dec 23 17:47:24 GMT+01:00 2019 INFO Bb: acquireConnection: source = activityResumed, refCount = 1 Mon Dec 23 17:47:24 GMT+01:00 2019 INFO Bb: another connection is already active Mon Dec 23 17:47:24 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now resumed Mon Dec 23 17:47:28 GMT+01:00 2019 INFO p: ICE connection state change to DISCONNECTED Mon Dec 23 17:47:28 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 17:47:28 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CONNECTED to CONNECTING and signaling state = TASK Mon Dec 23 17:47:38 GMT+01:00 2019 INFO p: ICE connection state change to FAILED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO p: PeerConnectionState changed to FAILED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CONNECTING to FAILED and signaling state = TASK Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/CONNECTED: Disconnecting: DisconnectContext{requestedByUs=false, reason=null} Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTING: Initializing Mon Dec 23 17:47:38 GMT+01:00 2019 INFO B: 0: Session state changed from CONNECTED to DISCONNECTING Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTING: Enter Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTING: Preparing disconnect timer... Mon Dec 23 17:47:38 GMT+01:00 2019 INFO m: 0: Signaling state changed to CLOSING Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTING: setDisconnecting - ignoring Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling close request Mon Dec 23 17:47:38 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Closing (remote) Mon Dec 23 17:47:38 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:47:38 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Closed (local) Mon Dec 23 17:47:38 GMT+01:00 2019 INFO m: 0: Signaling state changed to CLOSED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTING: setDisconnected Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Initializing Mon Dec 23 17:47:38 GMT+01:00 2019 INFO B: 0: Session state changed from DISCONNECTING to DISCONNECTED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Enter Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Cleanup Mon Dec 23 17:47:38 GMT+01:00 2019 INFO p: dispose() Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Cleanup done Mon Dec 23 17:47:38 GMT+01:00 2019 INFO p: ICE connection state change to CLOSED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Call WebClientServiceListener.onStopped() Mon Dec 23 17:47:38 GMT+01:00 2019 INFO p: PeerConnectionState changed to CLOSED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Checking for pending wakeups Mon Dec 23 17:47:38 GMT+01:00 2019 INFO p: Signaling state change to CLOSED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO y: Process 0 pending wakeups Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling closed Mon Dec 23 17:47:38 GMT+01:00 2019 INFO m: 0: Peer connection state changed from FAILED to CLOSED and signaling state = CLOSED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO m: 0: Data channel THREEMA closed Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: setDisconnected - ignoring Mon Dec 23 17:47:38 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStopped Mon Dec 23 17:47:38 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:47:38 GMT+01:00 2019 INFO r: 0: Stopping session instance: DisconnectContext{requestedByUs=false, reason=null} Mon Dec 23 17:47:38 GMT+01:00 2019 INFO ThreemaApplication: sending ACTION_STOP to SessionAndroidService Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: setDisconnecting - ignoring Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: setDisconnecting - ignoring Mon Dec 23 17:47:38 GMT+01:00 2019 INFO ThreemaApplication: updating SessionAndroidService Mon Dec 23 17:47:38 GMT+01:00 2019 INFO ThreemaApplication: sending ACTION_UPDATE to SessionAndroidService Mon Dec 23 17:47:38 GMT+01:00 2019 INFO SessionAndroidService: ACTION_STOP Mon Dec 23 17:47:38 GMT+01:00 2019 INFO SessionAndroidService: ACTION_UPDATE Mon Dec 23 17:47:38 GMT+01:00 2019 INFO SessionAndroidService: No more running sessions Mon Dec 23 17:47:38 GMT+01:00 2019 INFO SessionAndroidService: stopForeground Mon Dec 23 17:47:38 GMT+01:00 2019 INFO p: All native resources disposed Mon Dec 23 17:47:38 GMT+01:00 2019 INFO SessionAndroidService: Service destroyed Mon Dec 23 17:47:38 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CLOSED to CLOSED and signaling state = CLOSED Mon Dec 23 17:47:38 GMT+01:00 2019 INFO o: 0/DISCONNECTED: setDisconnecting - ignoring ```

Here some logs. At ~ 17:57:25 I closed the session in the app. It was recognized immediately.

Threema Web logs ``` { "config": { "VERSION": "2.3.1", "SELF_HOSTED": false, "VERSION_MOUNTAIN": "Säntis", "VERSION_MOUNTAIN_URL": "https://en.wikipedia.org/wiki/Säntis", "VERSION_MOUNTAIN_IMAGE_URL": "https://en.wikipedia.org/wiki/S%C3%A4ntis#/media/File:S%C3%A4ntis_mountain_by_sunset..JPG", "VERSION_MOUNTAIN_IMAGE_COPYRIGHT": "CC BY-SA 3.0 B0rder", "VERSION_MOUNTAIN_HEIGHT": 2502, "PREV_PROTOCOL_LAST_VERSION": "1.8.2", "GIT_BRANCH": "master", "SALTYRTC_HOST": "saltyrtc-{prefix}.threema.ch", "SALTYRTC_PORT": 443, "SALTYRTC_SERVER_KEY": "b1337fc8402f7db8ea639e05ed05d65463e24809792f91eca29e88101b4a2171", "ICE_SERVERS": [ { "urls": [ "turn:turn-{prefix}.threema.ch:443?transport=udp", "turn:turn-{prefix}.threema.ch:443?transport=tcp", "turns:turn-{prefix}.threema.ch:443" ], "username": "[String]", "credential": "[String]" } ], "PUSH_URL": "https://push-web.threema.ch/push", "LOG_TAG_PADDING": 20, "CONSOLE_LOG_LEVEL": "info", "REPORT_LOG_LEVEL": "debug", "REPORT_LOG_LIMIT": 1000, "COMPOSE_AREA_LOG_LEVEL": "warn", "SALTYRTC_LOG_LEVEL": "warn", "TIMER_LOG_LEVEL": "info", "ARP_LOG_LEVEL": "debug", "ARP_LOG_TRACE": false, "MSGPACK_LOG_TRACE": false, "TRANSPORT_LOG_LEVEL": "warn", "VISUALIZE_STATE": false }, "browser": "Firefox 71", "log": [ [ 1577120195299, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577120195310, "debug", "%c[Theme-S]", "color: #fff; background-color: #cc9900", "Initializing with theme threema" ], [ 1577120195311, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120195392, "debug", "[Welcome-C]", "Detected browser:", "Firefox 71" ], [ 1577120195392, "debug", "[Settings-S]", "Removing settings key:", "v2infoShown" ], [ 1577120195394, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120195395, "info", "[Welcome-C]", "Initialize session by scanning QR code..." ], [ 1577120195395, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=false, close=welcome, connectionBuildupState=new)" ], [ 1577120195395, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577120195395, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577120195395, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120195396, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577120195396, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection was null" ], [ 1577120195397, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing (keyStore=no, peerTrustedKey=no, \n resume=false)" ], [ 1577120195397, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120195397, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Discarded previous session" ], [ 1577120195421, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Public key:", "a018e75406036b85a05f2c8a11deaff8dc551bd36bd4a13b31fb0d0b0d0eeb67" ], [ 1577120195422, "debug", "[Welcome-C]", "Checking if the session is already open in another tab or window" ], [ 1577120195431, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Starting WebClientService..." ], [ 1577120195431, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Creating new startup promise" ], [ 1577120195432, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "new", "=>", "connecting" ], [ 1577120195432, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "new" ], [ 1577120195433, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "ws-connecting" ], [ 1577120195505, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: error -> warning (attempt=0)" ], [ 1577120195522, "info", "[Version-S]", "Using Threema Web version", "2.3.1" ], [ 1577120195743, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "ws-connecting", "=>", "server-handshake" ], [ 1577120195830, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "connecting", "=>", "waiting" ], [ 1577120195830, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "server-handshake", "=>", "peer-handshake" ], [ 1577120225358, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "waiting", "=>", "peer_handshake" ], [ 1577120225362, "info", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Initialize WebRTC PeerConnection" ], [ 1577120225363, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE servers used:", [ "turn:turn-3e.threema.ch:443?transport=udp", "turn:turn-3e.threema.ch:443?transport=tcp", "turns:turn-3e.threema.ch:443" ] ], [ 1577120225366, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Setting up ICE candidate handling" ], [ 1577120225368, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "peer-handshake", "=>", "task" ], [ 1577120225368, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "RTCPeerConnection: negotiation needed" ], [ 1577120225431, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signaling state change:", "have-local-offer" ], [ 1577120225431, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Created offer, set local description" ], [ 1577120225434, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE gathering state change:", "gathering" ], [ 1577120225435, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Gathered local ICE candidate:", "candidate:0 1 UDP 2122252543 2003:*:*:*:*:*:*:* 55476 typ host" ], [ 1577120225435, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Gathered local ICE candidate:", "candidate:3 1 TCP 2105524479 2003:*:*:*:*:*:*:* 9 typ host tcptype active" ], [ 1577120225482, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE gathering state change:", "complete" ], [ 1577120225482, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "No more local ICE candidates" ], [ 1577120225800, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signaling state change:", "stable" ], [ 1577120225801, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Received answer, set remote description" ], [ 1577120225802, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Initiator flow done" ], [ 1577120225802, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE connection state change:", "checking" ], [ 1577120225802, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Task connection state:", "new", "=>", "connecting" ], [ 1577120225803, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Connection state change:", "connecting" ], [ 1577120225811, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE connection state change:", "connected" ], [ 1577120225814, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Task connection state:", "connecting", "=>", "connected" ], [ 1577120225814, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Connection state change:", "connected" ], [ 1577120225815, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: warning -> ok (attempt=0)" ], [ 1577120225836, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Adding remote ICE candidate:", "candidate:2957806663 1 UDP 2122262783 2003:*:*:*:*:*:*:* 47389 typ host ufrag ObYN" ], [ 1577120225862, "info", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signalling data channel open" ], [ 1577120225870, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Adding remote ICE candidate:", "candidate:3455323002 1 UDP 2122194687 192.168.*.* 48496 typ host ufrag ObYN" ], [ 1577120225906, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Handover done" ], [ 1577120225906, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing NotificationService..." ], [ 1577120225907, "debug", "[Notification-S]", "Notification API available:", true ], [ 1577120225907, "debug", "[Notification-S]", "Initial notificationPermission", false ], [ 1577120225907, "debug", "[Notification-S]", "Fetching settings..." ], [ 1577120225907, "debug", "[Settings-S]", "Retrieving settings key:", "notifications" ], [ 1577120225907, "debug", "[Settings-S]", "Retrieving settings key:", "notificationPreview" ], [ 1577120225907, "debug", "[Settings-S]", "Retrieving settings key:", "notificationSound" ], [ 1577120225907, "debug", "[Notification-S]", "Desktop notifications:", "", "Asking user..." ], [ 1577120225907, "debug", "[Notification-S]", "Requesting notification permission..." ], [ 1577120225907, "debug", "[Notification-S]", "Notification preview:", "true", "Using default value (true)" ], [ 1577120225907, "debug", "[Settings-S]", "Storing settings key:", "notificationPreview" ], [ 1577120225907, "debug", "[Notification-S]", "Notification sound:", "false", "Using default value (false)" ], [ 1577120225907, "debug", "[Settings-S]", "Storing settings key:", "notificationSound" ], [ 1577120225908, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Creating data channel THREEMA" ], [ 1577120225909, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Data channel THREEMA open" ], [ 1577120225910, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Using chunk length: 65536 for data channel THREEMA" ], [ 1577120225910, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending connection info (resume=no)" ], [ 1577120225910, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "update/connectionInfo", "message" ], [ 1577120225915, "debug", "[Notification-S]", "Notification permission", false ], [ 1577120225947, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (79 bytes)" ], [ 1577120225949, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received update/connectionInfo message" ], [ 1577120225949, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received connectionInfo from device" ], [ 1577120225950, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received connection info (resume=no, sn-out=n/a)" ], [ 1577120225950, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Cannot resume session: Remote did not implement deriving the connection ID" ], [ 1577120225950, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session discarded" ], [ 1577120225950, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120225950, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending client info request" ], [ 1577120225951, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/clientInfo", "message" ], [ 1577120225953, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending profile request" ], [ 1577120225953, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/profile", "message" ], [ 1577120225954, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending receivers request" ], [ 1577120225954, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/receivers", "message" ], [ 1577120225956, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending conversation request" ], [ 1577120225956, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/conversations", "message" ], [ 1577120225957, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending battery status request" ], [ 1577120225958, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/batteryStatus", "message" ], [ 1577120225959, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Required initialization step", "client info", "not completed, add pending routine" ], [ 1577120225959, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "peer_handshake", "=>", "loading" ], [ 1577120226020, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (437 bytes)" ], [ 1577120226021, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/clientInfo message" ], [ 1577120226022, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received client info response" ], [ 1577120226022, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Client device:", "MotoE2(4G-LTE)" ], [ 1577120226022, "info", "%c[Push-S]", "color: #fff; background-color: #9900ff", "Initialized with", "gcm", "token" ], [ 1577120226023, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"client info\" done" ], [ 1577120226023, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (62 bytes)" ], [ 1577120226023, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received update/batteryStatus message" ], [ 1577120226024, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received battery status" ], [ 1577120226024, "debug", "[BatteryStatus-S]", "Status:", { "percent": 36, "isCharging": false } ], [ 1577120226101, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (4298 bytes)" ], [ 1577120226102, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/profile message" ], [ 1577120226102, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received profile response" ], [ 1577120226102, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"profile\" done" ], [ 1577120226142, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (3511 bytes)" ], [ 1577120226145, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/receivers message" ], [ 1577120226146, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received receivers response" ], [ 1577120226146, "debug", "[Browser-S]", "Browser supports extended locale compare options" ], [ 1577120226149, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"receivers\" done" ], [ 1577120226174, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (8539 bytes)" ], [ 1577120226175, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/conversations message" ], [ 1577120226175, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received conversations response" ], [ 1577120226176, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"conversations\" done" ], [ 1577120226176, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Running routine after initialization \"conversations\" completed" ], [ 1577120226177, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "loading", "=>", "done" ], [ 1577120226177, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120232960, "debug", "[Version-S]", "Checking for version update..." ], [ 1577120245942, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=true, close=welcome, connectionBuildupState=closed)" ], [ 1577120245942, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "update/connectionDisconnect", "message" ], [ 1577120245943, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577120245943, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577120245944, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120245944, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577120245944, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing data channel" ], [ 1577120245944, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing signaling" ], [ 1577120245944, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signalling data channel close request" ], [ 1577120245946, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection closed" ], [ 1577120245965, "debug", "[Welcome-C]", "Detected browser:", "Firefox 71" ], [ 1577120245966, "debug", "[Settings-S]", "Removing settings key:", "v2infoShown" ], [ 1577120245966, "debug", "[Version-S]", "Checking for version update..." ], [ 1577120245966, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120245967, "info", "[Welcome-C]", "Initialize session by scanning QR code..." ], [ 1577120245967, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=false, close=welcome, connectionBuildupState=closed)" ], [ 1577120245967, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577120245967, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577120245967, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120245967, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577120245967, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing data channel" ], [ 1577120245967, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing signaling" ], [ 1577120245967, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection closed" ], [ 1577120245973, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing (keyStore=no, peerTrustedKey=no, \n resume=false)" ], [ 1577120245973, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577120245973, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Discarded previous session" ], [ 1577120245981, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Public key:", "c56584af1d65bb72c0cefa3d75f8405850d68c20757c3ecf71927e2323a0d60f" ], [ 1577120245981, "debug", "[Welcome-C]", "Checking if the session is already open in another tab or window" ], [ 1577120246002, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: ok -> error (attempt=0)" ], [ 1577120246016, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Starting WebClientService..." ], [ 1577120246016, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Creating new startup promise" ], [ 1577120246016, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "closed", "=>", "connecting" ], [ 1577120246016, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "new" ], [ 1577120246016, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "ws-connecting" ], [ 1577120246072, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: error -> warning (attempt=0)" ], [ 1577120246440, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "ws-connecting", "=>", "server-handshake" ], [ 1577120246494, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "connecting", "=>", "waiting" ], [ 1577120246494, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "server-handshake", "=>", "peer-handshake" ] ] } ```
App logs ``` Mon Dec 23 17:57:00 GMT+01:00 2019 INFO Bb: releaseConnectionLinger: source = activityPaused, timeout = 60000 Mon Dec 23 17:57:00 GMT+01:00 2019 INFO Bb: Alarm type 1 cancel Mon Dec 23 17:57:00 GMT+01:00 2019 INFO Bb: Alarm type 1 schedule in 59997ms Mon Dec 23 17:57:01 GMT+01:00 2019 INFO Bb: acquireConnection: source = activityResumed, refCount = 1 Mon Dec 23 17:57:01 GMT+01:00 2019 INFO Bb: another connection is already active Mon Dec 23 17:57:02 GMT+01:00 2019 INFO SessionsActivity: Initiate QR scan Mon Dec 23 17:57:02 GMT+01:00 2019 INFO Bb: releaseConnectionLinger: source = activityPaused, timeout = 60000 Mon Dec 23 17:57:02 GMT+01:00 2019 INFO Bb: Alarm type 1 cancel Mon Dec 23 17:57:02 GMT+01:00 2019 INFO Bb: Alarm type 1 schedule in 59997ms Mon Dec 23 17:57:03 GMT+01:00 2019 INFO c: Screen resolution in current orientation: Point(540, 960) Mon Dec 23 17:57:03 GMT+01:00 2019 INFO c: Camera resolution: Point(960, 540) Mon Dec 23 17:57:03 GMT+01:00 2019 INFO c: Best available preview size: Point(960, 540) Mon Dec 23 17:57:04 GMT+01:00 2019 INFO SessionsActivity: Starting Threema Web session Mon Dec 23 17:57:04 GMT+01:00 2019 INFO Bb: acquireConnection: source = activityResumed, refCount = 1 Mon Dec 23 17:57:04 GMT+01:00 2019 INFO Bb: another connection is already active Mon Dec 23 17:57:04 GMT+01:00 2019 INFO y: Process 0 pending wakeups Mon Dec 23 17:57:04 GMT+01:00 2019 INFO r: 0: Initialize SessionInstanceServiceImpl Mon Dec 23 17:57:04 GMT+01:00 2019 INFO o: 0/NEW: Initializing Mon Dec 23 17:57:04 GMT+01:00 2019 INFO r: 0: Starting Threema Web session Mon Dec 23 17:57:04 GMT+01:00 2019 INFO o: 0/NEW: setConnecting Mon Dec 23 17:57:04 GMT+01:00 2019 INFO o: 0/CONNECTING: Initializing Mon Dec 23 17:57:04 GMT+01:00 2019 INFO B: 0: Session state changed from NEW to CONNECTING Mon Dec 23 17:57:04 GMT+01:00 2019 INFO o: 0/CONNECTING: Enter Mon Dec 23 17:57:04 GMT+01:00 2019 INFO o: 0/CONNECTING: Starting connection 0 of session 0 Mon Dec 23 17:57:04 GMT+01:00 2019 INFO o: 0/CONNECTING: Acquire session resources... Mon Dec 23 17:57:04 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Connecting to SaltyRTC server at saltyrtc-a0.threema.ch:443... Mon Dec 23 17:57:04 GMT+01:00 2019 INFO m: 0: Signaling state changed to WS_CONNECTING Mon Dec 23 17:57:04 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:57:04 GMT+01:00 2019 INFO SaltyRTC.RSignaling: WebSocket connection established Mon Dec 23 17:57:04 GMT+01:00 2019 INFO m: 0: Signaling state changed to SERVER_HANDSHAKE Mon Dec 23 17:57:05 GMT+01:00 2019 INFO m: 0: Signaling state changed to PEER_HANDSHAKE Mon Dec 23 17:57:05 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Server handshake done Mon Dec 23 17:57:05 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Task v1.webrtc.tasks.saltyrtc.org has been selected Mon Dec 23 17:57:05 GMT+01:00 2019 INFO m: 0: Signaling state changed to TASK Mon Dec 23 17:57:05 GMT+01:00 2019 INFO o: 0/CONNECTING: Handover Mon Dec 23 17:57:05 GMT+01:00 2019 INFO o: 0/CONNECTING: Cancelling connect timeout Mon Dec 23 17:57:05 GMT+01:00 2019 INFO o: 0/HANDOVER: Initializing Mon Dec 23 17:57:05 GMT+01:00 2019 INFO B: 0: Session state changed from CONNECTING to HANDOVER Mon Dec 23 17:57:05 GMT+01:00 2019 INFO o: 0/HANDOVER: Enter Mon Dec 23 17:57:05 GMT+01:00 2019 INFO o: 0/HANDOVER: Starting handover... Mon Dec 23 17:57:05 GMT+01:00 2019 INFO p: Initialize WebRTC PeerConnection Mon Dec 23 17:57:05 GMT+01:00 2019 INFO p: Renegotiation needed Mon Dec 23 17:57:05 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 17:57:05 GMT+01:00 2019 INFO m: 0: Peer connection state changed from NEW to CONNECTING and signaling state = TASK Mon Dec 23 17:57:05 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Peer handshake done Mon Dec 23 17:57:05 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:57:05 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: offer Mon Dec 23 17:57:05 GMT+01:00 2019 INFO p: Signaling state change to HAVE_REMOTE_OFFER Mon Dec 23 17:57:05 GMT+01:00 2019 INFO p: Remote description set Mon Dec 23 17:57:05 GMT+01:00 2019 INFO p: Created answer Mon Dec 23 17:57:05 GMT+01:00 2019 INFO p: Signaling state change to STABLE Mon Dec 23 17:57:05 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: candidates Mon Dec 23 17:57:05 GMT+01:00 2019 INFO p: Adding peer ICE candidate: candidate:0 1 UDP 2122252543 2003:d8:7714:1a00:bded:dd7f:e341:c7fd 55476 typ host Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: Local description set Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: ICE gathering state change to GATHERING Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: ICE connection state change to CHECKING Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: Adding peer ICE candidate: candidate:3 1 TCP 2105524479 2003:d8:7714:1a00:bded:dd7f:e341:c7fd 9 typ host tcptype active Mon Dec 23 17:57:06 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CONNECTING to CONNECTING and signaling state = TASK Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: Sending local ICE candidate: candidate:2957806663 1 udp 2122262783 2003:d8:7714:1a00:e0cf:e12b:b4f9:903c 47389 typ host generation 0 ufrag ObYN network-id 4 network-cost 10 Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: Sending local ICE candidate: candidate:3455323002 1 udp 2122194687 192.168.178.20 48496 typ host generation 0 ufrag ObYN network-id 3 network-cost 10 Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: Added 2 ICE candidate(s) from peer Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: ICE gathering state change to COMPLETE Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: ICE connection state change to CONNECTED Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTED Mon Dec 23 17:57:06 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CONNECTING to CONNECTED and signaling state = TASK Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling open Mon Dec 23 17:57:06 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Signaling transport created Mon Dec 23 17:57:06 GMT+01:00 2019 INFO p: New data channel: THREEMA Mon Dec 23 17:57:06 GMT+01:00 2019 INFO m: 0: Data channel THREEMA open Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/HANDOVER: Connected Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/HANDOVER: Cancelling handover timeout Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Initializing Mon Dec 23 17:57:06 GMT+01:00 2019 INFO B: 0: Session state changed from HANDOVER to CONNECTED Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Enter Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending update/connectionInfo to peer Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:57:06 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: handover Mon Dec 23 17:57:06 GMT+01:00 2019 INFO m: 0: Handover done Mon Dec 23 17:57:06 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: Handover to data channel finished Mon Dec 23 17:57:06 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:57:06 GMT+01:00 2019 INFO j: Received client information request Mon Dec 23 17:57:06 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStarted Mon Dec 23 17:57:06 GMT+01:00 2019 INFO ThreemaApplication: SessionAndroidService not running...starting Mon Dec 23 17:57:06 GMT+01:00 2019 INFO ThreemaApplication: sending ACTION_START to SessionAndroidService Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:57:06 GMT+01:00 2019 INFO r: 0: Client info sent after 1688 ms Mon Dec 23 17:57:06 GMT+01:00 2019 INFO r: 0: Registered for profile updates Mon Dec 23 17:57:06 GMT+01:00 2019 INFO SessionAndroidService: startForeground called Mon Dec 23 17:57:06 GMT+01:00 2019 INFO SessionAndroidService: ACTION_START Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:57:06 GMT+01:00 2019 INFO r: 0: Profile sent after 1759 ms Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:57:06 GMT+01:00 2019 INFO r: 0: Receivers sent after 1801 ms Mon Dec 23 17:57:06 GMT+01:00 2019 INFO o: 0/CONNECTED: Sending message through data channel (async) Mon Dec 23 17:57:06 GMT+01:00 2019 INFO r: 0: Conversations sent after 1820 ms Mon Dec 23 17:57:33 GMT+01:00 2019 INFO p: ICE connection state change to DISCONNECTED Mon Dec 23 17:57:33 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 17:57:33 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CONNECTED to CONNECTING and signaling state = TASK Mon Dec 23 17:57:40 GMT+01:00 2019 INFO Bb: releaseConnectionLinger: source = activityPaused, timeout = 60000 Mon Dec 23 17:57:40 GMT+01:00 2019 INFO Bb: Alarm type 1 cancel Mon Dec 23 17:57:40 GMT+01:00 2019 INFO Bb: Alarm type 1 schedule in 59977ms Mon Dec 23 17:57:41 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now paused Mon Dec 23 17:57:41 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now hidden Mon Dec 23 17:57:43 GMT+01:00 2019 INFO p: ICE connection state change to FAILED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO p: PeerConnectionState changed to FAILED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CONNECTING to FAILED and signaling state = TASK Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/CONNECTED: Disconnecting: DisconnectContext{requestedByUs=false, reason=null} Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTING: Initializing Mon Dec 23 17:57:43 GMT+01:00 2019 INFO B: 0: Session state changed from CONNECTED to DISCONNECTING Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTING: Enter Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTING: Preparing disconnect timer... Mon Dec 23 17:57:43 GMT+01:00 2019 INFO m: 0: Signaling state changed to CLOSING Mon Dec 23 17:57:43 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTING: setDisconnecting - ignoring Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling close request Mon Dec 23 17:57:43 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Closing (remote) Mon Dec 23 17:57:43 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Closed (local) Mon Dec 23 17:57:43 GMT+01:00 2019 INFO m: 0: Signaling state changed to CLOSED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTING: setDisconnected Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Initializing Mon Dec 23 17:57:43 GMT+01:00 2019 INFO B: 0: Session state changed from DISCONNECTING to DISCONNECTED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Enter Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Cleanup Mon Dec 23 17:57:43 GMT+01:00 2019 INFO p: dispose() Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Cleanup done Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Call WebClientServiceListener.onStopped() Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: Checking for pending wakeups Mon Dec 23 17:57:43 GMT+01:00 2019 INFO y: Process 0 pending wakeups Mon Dec 23 17:57:43 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStopped Mon Dec 23 17:57:43 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 17:57:43 GMT+01:00 2019 INFO ThreemaApplication: sending ACTION_STOP to SessionAndroidService Mon Dec 23 17:57:43 GMT+01:00 2019 INFO r: 0: Stopping session instance: DisconnectContext{requestedByUs=false, reason=null} Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: setDisconnecting - ignoring Mon Dec 23 17:57:43 GMT+01:00 2019 INFO p: ICE connection state change to CLOSED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO p: PeerConnectionState changed to CLOSED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO p: Signaling state change to CLOSED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling closed Mon Dec 23 17:57:43 GMT+01:00 2019 INFO m: 0: Peer connection state changed from FAILED to CLOSED and signaling state = CLOSED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: setDisconnecting - ignoring Mon Dec 23 17:57:43 GMT+01:00 2019 INFO m: 0: Data channel THREEMA closed Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: setDisconnected - ignoring Mon Dec 23 17:57:43 GMT+01:00 2019 INFO ThreemaApplication: updating SessionAndroidService Mon Dec 23 17:57:43 GMT+01:00 2019 INFO ThreemaApplication: sending ACTION_UPDATE to SessionAndroidService Mon Dec 23 17:57:43 GMT+01:00 2019 INFO SessionAndroidService: ACTION_STOP Mon Dec 23 17:57:43 GMT+01:00 2019 INFO SessionAndroidService: ACTION_UPDATE Mon Dec 23 17:57:43 GMT+01:00 2019 INFO SessionAndroidService: No more running sessions Mon Dec 23 17:57:43 GMT+01:00 2019 INFO SessionAndroidService: stopForeground Mon Dec 23 17:57:43 GMT+01:00 2019 INFO SessionAndroidService: Service destroyed Mon Dec 23 17:57:43 GMT+01:00 2019 INFO p: All native resources disposed Mon Dec 23 17:57:43 GMT+01:00 2019 INFO m: 0: Peer connection state changed from CLOSED to CLOSED and signaling state = CLOSED Mon Dec 23 17:57:43 GMT+01:00 2019 INFO o: 0/DISCONNECTED: setDisconnecting - ignoring ```

The Threema Web logs are more or less identical, so there is only a difference in the app logs.

lgrahl commented 4 years ago

Should be fixed in the upcoming beta.