search

threema-ch / threema-web

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

Reconnection to recently closed session fails #949

Closed ovalseven8 closed 4 years ago

ovalseven8 commented 4 years ago

Steps to Reproduce (for bugs)

  1. Create new Threema Web session (with password)
  2. Once you're connected, close the session within Threema Web
  3. Try to reconnect
  4. Reconnection fails (stuck at 60 %)

Peek 2019-12-23 10-40

What's perhaps important to mention: Right before Threema Web reaches 15 %, the session in the Android app gets this red exclamation mark: Screenshot_20191223-104839

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": [ [ 1577093958303, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577093958316, "debug", "%c[Theme-S]", "color: #fff; background-color: #cc9900", "Initializing with theme threema" ], [ 1577093958316, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577093958394, "debug", "[Welcome-C]", "Detected browser:", "Firefox 71" ], [ 1577093958394, "debug", "[Settings-S]", "Removing settings key:", "v2infoShown" ], [ 1577093958397, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577093958398, "info", "[Welcome-C]", "Initialize session by scanning QR code..." ], [ 1577093958398, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=false, close=welcome, connectionBuildupState=new)" ], [ 1577093958398, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577093958398, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577093958398, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577093958398, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577093958398, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection was null" ], [ 1577093958400, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing (keyStore=no, peerTrustedKey=no, \n resume=false)" ], [ 1577093958400, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577093958400, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Discarded previous session" ], [ 1577093958424, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Public key:", "35979875d6eb888febbae832513b8fa911d6fdebcbfb75b8353a3848a4dd0512" ], [ 1577093958425, "debug", "[Welcome-C]", "Checking if the session is already open in another tab or window" ], [ 1577093958436, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Starting WebClientService..." ], [ 1577093958437, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Creating new startup promise" ], [ 1577093958437, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "new", "=>", "connecting" ], [ 1577093958437, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "new" ], [ 1577093958439, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "ws-connecting" ], [ 1577093958518, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: error -> warning (attempt=0)" ], [ 1577093958535, "info", "[Version-S]", "Using Threema Web version", "2.3.1" ], [ 1577093958769, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "ws-connecting", "=>", "server-handshake" ], [ 1577093958870, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "connecting", "=>", "waiting" ], [ 1577093958871, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "server-handshake", "=>", "peer-handshake" ], [ 1577094007341, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "waiting", "=>", "peer_handshake" ], [ 1577094007346, "info", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Initialize WebRTC PeerConnection" ], [ 1577094007346, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE servers used:", [ "turn:turn-7a.threema.ch:443?transport=udp", "turn:turn-7a.threema.ch:443?transport=tcp", "turns:turn-7a.threema.ch:443" ] ], [ 1577094007351, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Setting up ICE candidate handling" ], [ 1577094007353, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "peer-handshake", "=>", "task" ], [ 1577094007354, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "RTCPeerConnection: negotiation needed" ], [ 1577094007407, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signaling state change:", "have-local-offer" ], [ 1577094007407, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Created offer, set local description" ], [ 1577094007422, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE gathering state change:", "gathering" ], [ 1577094007423, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Gathered local ICE candidate:", "candidate:0 1 UDP 2122252543 2003:*:*:*:*:*:*:* 49903 typ host" ], [ 1577094007424, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Gathered local ICE candidate:", "candidate:3 1 TCP 2105524479 2003:*:*:*:*:*:*:* 9 typ host tcptype active" ], [ 1577094007481, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE gathering state change:", "complete" ], [ 1577094007482, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "No more local ICE candidates" ], [ 1577094007571, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signaling state change:", "stable" ], [ 1577094007572, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Received answer, set remote description" ], [ 1577094007572, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Initiator flow done" ], [ 1577094007573, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE connection state change:", "checking" ], [ 1577094007573, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Task connection state:", "new", "=>", "connecting" ], [ 1577094007573, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Connection state change:", "connecting" ], [ 1577094007576, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE connection state change:", "connected" ], [ 1577094007577, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Task connection state:", "connecting", "=>", "connected" ], [ 1577094007577, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Connection state change:", "connected" ], [ 1577094007578, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: warning -> ok (attempt=0)" ], [ 1577094007616, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Adding remote ICE candidate:", "candidate:2597189828 1 UDP 2122262783 2003:*:*:*:*:*:*:* 58492 typ host ufrag xvol" ], [ 1577094007620, "info", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signalling data channel open" ], [ 1577094007665, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Adding remote ICE candidate:", "candidate:3455323002 1 UDP 2122194687 192.168.*.* 41759 typ host ufrag xvol" ], [ 1577094007666, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Handover done" ], [ 1577094007667, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing NotificationService..." ], [ 1577094007667, "debug", "[Notification-S]", "Notification API available:", true ], [ 1577094007667, "debug", "[Notification-S]", "Initial notificationPermission", false ], [ 1577094007667, "debug", "[Notification-S]", "Fetching settings..." ], [ 1577094007667, "debug", "[Settings-S]", "Retrieving settings key:", "notifications" ], [ 1577094007667, "debug", "[Settings-S]", "Retrieving settings key:", "notificationPreview" ], [ 1577094007667, "debug", "[Settings-S]", "Retrieving settings key:", "notificationSound" ], [ 1577094007667, "debug", "[Notification-S]", "Desktop notifications:", "", "Asking user..." ], [ 1577094007667, "debug", "[Notification-S]", "Requesting notification permission..." ], [ 1577094007667, "debug", "[Notification-S]", "Notification preview:", "true", "Using default value (true)" ], [ 1577094007667, "debug", "[Settings-S]", "Storing settings key:", "notificationPreview" ], [ 1577094007667, "debug", "[Notification-S]", "Notification sound:", "false", "Using default value (false)" ], [ 1577094007667, "debug", "[Settings-S]", "Storing settings key:", "notificationSound" ], [ 1577094007668, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Creating data channel THREEMA" ], [ 1577094007669, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Data channel THREEMA open" ], [ 1577094007669, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Using chunk length: 65536 for data channel THREEMA" ], [ 1577094007670, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending connection info (resume=no)" ], [ 1577094007670, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "update/connectionInfo", "message" ], [ 1577094007676, "debug", "[Notification-S]", "Notification permission", false ], [ 1577094007685, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (79 bytes)" ], [ 1577094007685, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received update/connectionInfo message" ], [ 1577094007686, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received connectionInfo from device" ], [ 1577094007689, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received connection info (resume=no, sn-out=n/a)" ], [ 1577094007689, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Cannot resume session: Remote did not implement deriving the connection ID" ], [ 1577094007689, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session discarded" ], [ 1577094007689, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577094007690, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending client info request" ], [ 1577094007690, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/clientInfo", "message" ], [ 1577094007692, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending profile request" ], [ 1577094007692, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/profile", "message" ], [ 1577094007692, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending receivers request" ], [ 1577094007693, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/receivers", "message" ], [ 1577094007695, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending conversation request" ], [ 1577094007695, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/conversations", "message" ], [ 1577094007696, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending battery status request" ], [ 1577094007696, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/batteryStatus", "message" ], [ 1577094007696, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Required initialization step", "client info", "not completed, add pending routine" ], [ 1577094007696, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "peer_handshake", "=>", "loading" ], [ 1577094007743, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (437 bytes)" ], [ 1577094007743, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/clientInfo message" ], [ 1577094007744, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received client info response" ], [ 1577094007745, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Client device:", "MotoE2(4G-LTE)" ], [ 1577094007745, "info", "%c[Push-S]", "color: #fff; background-color: #9900ff", "Initialized with", "gcm", "token" ], [ 1577094007745, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"client info\" done" ], [ 1577094007747, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (62 bytes)" ], [ 1577094007747, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received update/batteryStatus message" ], [ 1577094007747, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received battery status" ], [ 1577094007747, "debug", "[BatteryStatus-S]", "Status:", { "percent": 54, "isCharging": false } ], [ 1577094007797, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (4298 bytes)" ], [ 1577094007797, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/profile message" ], [ 1577094007797, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received profile response" ], [ 1577094007797, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"profile\" done" ], [ 1577094007826, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (3511 bytes)" ], [ 1577094007828, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/receivers message" ], [ 1577094007828, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received receivers response" ], [ 1577094007829, "debug", "[Browser-S]", "Browser supports extended locale compare options" ], [ 1577094007831, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"receivers\" done" ], [ 1577094007881, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "New incoming message (8410 bytes)" ], [ 1577094007883, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received response/conversations message" ], [ 1577094007883, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Received conversations response" ], [ 1577094007883, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Initialization step \"conversations\" done" ], [ 1577094007883, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Running routine after initialization \"conversations\" completed" ], [ 1577094007883, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "loading", "=>", "done" ], [ 1577094007884, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577094007892, "debug", "%c[TrustedKeyStore-S]", "color: #fff; background-color: #666699", "Storing trusted key" ], [ 1577094007894, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stored trusted key" ], [ 1577094007894, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "request/keyPersisted", "message" ], [ 1577094014697, "debug", "[Version-S]", "Checking for version update..." ], [ 1577094014863, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=true, close=welcome, connectionBuildupState=closed)" ], [ 1577094014863, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Sending", "update/connectionDisconnect", "message" ], [ 1577094014865, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577094014865, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577094014865, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577094014865, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577094014865, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing data channel" ], [ 1577094014865, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing signaling" ], [ 1577094014866, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signalling data channel close request" ], [ 1577094014866, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection closed" ], [ 1577094014886, "debug", "[Welcome-C]", "Detected browser:", "Firefox 71" ], [ 1577094014886, "debug", "[Settings-S]", "Removing settings key:", "v2infoShown" ], [ 1577094014886, "debug", "[Version-S]", "Checking for version update..." ], [ 1577094014887, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577094014887, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577094014887, "info", "[Welcome-C]", "Initialize session by unlocking trusted key..." ], [ 1577094014927, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: ok -> error (attempt=0)" ], [ 1577094029335, "debug", "[Welcome-C]", "Checking if the session is already open in another tab or window" ], [ 1577094029435, "debug", "[Welcome-C]", "No broadcast received indicating that a session is already open" ], [ 1577094029436, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Stopping (reason=stop, send=false, close=welcome, connectionBuildupState=new)" ], [ 1577094029436, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Timer stopped" ], [ 1577094029436, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Reset states" ], [ 1577094029436, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577094029437, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Session closed (cannot be resumed)" ], [ 1577094029437, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing data channel" ], [ 1577094029437, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Closing signaling" ], [ 1577094029437, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Peer connection closed" ], [ 1577094029444, "info", "%c[Push-S]", "color: #fff; background-color: #9900ff", "Initialized with", "gcm", "token" ], [ 1577094029445, "info", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Initializing (keyStore=yes, peerTrustedKey=yes, \n resume=false)" ], [ 1577094029445, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Reset initialization steps" ], [ 1577094029445, "debug", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Discarded previous session" ], [ 1577094029449, "info", "%c[AppRemoteProtocol]", "color: #fff; background-color: #0099cc", "Public key:", "35979875d6eb888febbae832513b8fa911d6fdebcbfb75b8353a3848a4dd0512" ], [ 1577094029449, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Starting WebClientService..." ], [ 1577094029449, "debug", "%c[WebClient-S]", "color: #fff; background-color: #0066cc", "Creating new startup promise" ], [ 1577094029449, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "new", "=>", "connecting" ], [ 1577094029449, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "new" ], [ 1577094029451, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "new", "=>", "ws-connecting" ], [ 1577094029452, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "connecting", "=>", "push" ], [ 1577094029492, "debug", "%c[Status-C]", "color: #000; background-color: #ffff99", "State change: error -> warning (attempt=0)" ], [ 1577094029497, "debug", "%c[Push.Kt7JTF]", "color: #fff; background-color: #9900cc", "Sending push 1/1 (ttl=90)" ], [ 1577094029647, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "ws-connecting", "=>", "server-handshake" ], [ 1577094029680, "debug", "%c[Push.Kt7JTF]", "color: #fff; background-color: #9900cc", "Push sent successfully" ], [ 1577094029739, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "server-handshake", "=>", "peer-handshake" ], [ 1577094033059, "info", "%c[Push.Kt7JTF]", "color: #fff; background-color: #9900cc", "Push done" ], [ 1577094033059, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Connection buildup state:", "push", "=>", "peer_handshake" ], [ 1577094033060, "info", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Initialize WebRTC PeerConnection" ], [ 1577094033060, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE servers used:", [ "turn:turn-3f.threema.ch:443?transport=udp", "turn:turn-3f.threema.ch:443?transport=tcp", "turns:turn-3f.threema.ch:443" ] ], [ 1577094033061, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Setting up ICE candidate handling" ], [ 1577094033062, "debug", "%c[State-S]", "color: #fff; background-color: #cc9900", "Signaling connection state:", "peer-handshake", "=>", "task" ], [ 1577094033062, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "RTCPeerConnection: negotiation needed" ], [ 1577094033077, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Signaling state change:", "have-local-offer" ], [ 1577094033077, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Created offer, set local description" ], [ 1577094033079, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE gathering state change:", "gathering" ], [ 1577094033080, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Gathered local ICE candidate:", "candidate:0 1 UDP 2122252543 2003:*:*:*:*:*:*:* 36681 typ host" ], [ 1577094033081, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "Gathered local ICE candidate:", "candidate:3 1 TCP 2105524479 2003:*:*:*:*:*:*:* 9 typ host tcptype active" ], [ 1577094033123, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "ICE gathering state change:", "complete" ], [ 1577094033124, "debug", "%c[PeerConnection]", "color: #fff; background-color: #3333ff", "No more local ICE candidates" ] ] } ```
App logs ``` Mon Dec 23 10:40:01 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now visible Mon Dec 23 10:40:01 GMT+01:00 2019 INFO Bb: acquireConnection: source = activityResumed, refCount = 1 Mon Dec 23 10:40:01 GMT+01:00 2019 INFO Bb: another connection is already active Mon Dec 23 10:40:01 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now resumed Mon Dec 23 10:40:07 GMT+01:00 2019 INFO SessionsActivity: Initiate QR scan Mon Dec 23 10:40:07 GMT+01:00 2019 INFO Bb: releaseConnectionLinger: source = activityPaused, timeout = 60000 Mon Dec 23 10:40:07 GMT+01:00 2019 INFO Bb: Alarm type 1 cancel Mon Dec 23 10:40:07 GMT+01:00 2019 INFO Bb: Alarm type 1 schedule in 59998ms Mon Dec 23 10:40:08 GMT+01:00 2019 INFO c: Screen resolution in current orientation: Point(540, 960) Mon Dec 23 10:40:08 GMT+01:00 2019 INFO c: Camera resolution: Point(960, 540) Mon Dec 23 10:40:08 GMT+01:00 2019 INFO c: Best available preview size: Point(960, 540) Mon Dec 23 10:40:08 GMT+01:00 2019 INFO Rc: Queue saved. Size = 0 Mon Dec 23 10:40:09 GMT+01:00 2019 INFO SessionsActivity: Starting Threema Web session Mon Dec 23 10:40:09 GMT+01:00 2019 INFO Bb: acquireConnection: source = activityResumed, refCount = 1 Mon Dec 23 10:40:09 GMT+01:00 2019 INFO Bb: another connection is already active Mon Dec 23 10:40:09 GMT+01:00 2019 INFO y: Process 0 pending wakeups Mon Dec 23 10:40:09 GMT+01:00 2019 INFO r: 11: Initialize SessionInstanceServiceImpl Mon Dec 23 10:40:09 GMT+01:00 2019 INFO o: 11/NEW: Initializing Mon Dec 23 10:40:09 GMT+01:00 2019 INFO r: 11: Starting Threema Web session Mon Dec 23 10:40:09 GMT+01:00 2019 INFO o: 11/NEW: setConnecting Mon Dec 23 10:40:09 GMT+01:00 2019 INFO o: 11/CONNECTING: Initializing Mon Dec 23 10:40:09 GMT+01:00 2019 INFO B: 11: Session state changed from NEW to CONNECTING Mon Dec 23 10:40:09 GMT+01:00 2019 INFO o: 11/CONNECTING: Enter Mon Dec 23 10:40:09 GMT+01:00 2019 INFO o: 11/CONNECTING: Starting connection 0 of session 11 Mon Dec 23 10:40:09 GMT+01:00 2019 INFO o: 11/CONNECTING: Acquire session resources... Mon Dec 23 10:40:09 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Connecting to SaltyRTC server at saltyrtc-35.threema.ch:443... Mon Dec 23 10:40:09 GMT+01:00 2019 INFO m: 11: Signaling state changed to WS_CONNECTING Mon Dec 23 10:40:09 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 10:40:09 GMT+01:00 2019 INFO SaltyRTC.RSignaling: WebSocket connection established Mon Dec 23 10:40:09 GMT+01:00 2019 INFO m: 11: Signaling state changed to SERVER_HANDSHAKE Mon Dec 23 10:40:09 GMT+01:00 2019 INFO m: 11: Signaling state changed to PEER_HANDSHAKE Mon Dec 23 10:40:09 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Server handshake done Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Task v1.webrtc.tasks.saltyrtc.org has been selected Mon Dec 23 10:40:10 GMT+01:00 2019 INFO m: 11: Signaling state changed to TASK Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTING: Handover Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTING: Cancelling connect timeout Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/HANDOVER: Initializing Mon Dec 23 10:40:10 GMT+01:00 2019 INFO B: 11: Session state changed from CONNECTING to HANDOVER Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/HANDOVER: Enter Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/HANDOVER: Starting handover... Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Initialize WebRTC PeerConnection Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Renegotiation needed Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 10:40:10 GMT+01:00 2019 INFO m: 11: Peer connection state changed from NEW to CONNECTING and signaling state = TASK Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Peer handshake done Mon Dec 23 10:40:10 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: offer Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Signaling state change to HAVE_REMOTE_OFFER Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Remote description set Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Created answer Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Signaling state change to STABLE Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: candidates Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Local description set Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Adding peer ICE candidate: candidate:0 1 UDP 2122252543 2003:d8:7727:b100:4d40:863a:2115:3d98 49903 typ host Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: ICE gathering state change to GATHERING Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: ICE connection state change to CHECKING Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Adding peer ICE candidate: candidate:3 1 TCP 2105524479 2003:d8:7727:b100:4d40:863a:2115:3d98 9 typ host tcptype active Mon Dec 23 10:40:10 GMT+01:00 2019 INFO m: 11: Peer connection state changed from CONNECTING to CONNECTING and signaling state = TASK Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Sending local ICE candidate: candidate:2597189828 1 udp 2122262783 2003:d8:7727:b100:11:5954:2e94:a214 58492 typ host generation 0 ufrag xvol network-id 4 network-cost 10 Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Sending local ICE candidate: candidate:3455323002 1 udp 2122194687 192.168.178.20 41759 typ host generation 0 ufrag xvol network-id 3 network-cost 10 Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: Added 2 ICE candidate(s) from peer Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: ICE gathering state change to COMPLETE Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: ICE connection state change to CONNECTED Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTED Mon Dec 23 10:40:10 GMT+01:00 2019 INFO m: 11: Peer connection state changed from CONNECTING to CONNECTED and signaling state = TASK Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling open Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Signaling transport created Mon Dec 23 10:40:10 GMT+01:00 2019 INFO p: New data channel: THREEMA Mon Dec 23 10:40:10 GMT+01:00 2019 INFO m: 11: Data channel THREEMA open Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/HANDOVER: Connected Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/HANDOVER: Cancelling handover timeout Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Initializing Mon Dec 23 10:40:10 GMT+01:00 2019 INFO B: 11: Session state changed from HANDOVER to CONNECTED Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Enter Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Sending update/connectionInfo to peer Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Sending message through data channel (async) Mon Dec 23 10:40:10 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: handover Mon Dec 23 10:40:10 GMT+01:00 2019 INFO m: 11: Handover done Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: Handover to data channel finished Mon Dec 23 10:40:10 GMT+01:00 2019 INFO j: Received client information request Mon Dec 23 10:40:10 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStarted Mon Dec 23 10:40:10 GMT+01:00 2019 INFO ThreemaApplication: SessionAndroidService not running...starting Mon Dec 23 10:40:10 GMT+01:00 2019 INFO ThreemaApplication: sending ACTION_START to SessionAndroidService Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Sending message through data channel (async) Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Sending message through data channel (async) Mon Dec 23 10:40:10 GMT+01:00 2019 INFO r: 11: Client info sent after 1192 ms Mon Dec 23 10:40:10 GMT+01:00 2019 INFO r: 11: Registered for profile updates Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SessionAndroidService: startForeground called Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Sending message through data channel (async) Mon Dec 23 10:40:10 GMT+01:00 2019 INFO r: 11: Profile sent after 1237 ms Mon Dec 23 10:40:10 GMT+01:00 2019 INFO SessionAndroidService: ACTION_START Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Sending message through data channel (async) Mon Dec 23 10:40:10 GMT+01:00 2019 INFO r: 11: Receivers sent after 1266 ms Mon Dec 23 10:40:10 GMT+01:00 2019 INFO o: 11/CONNECTED: Sending message through data channel (async) Mon Dec 23 10:40:10 GMT+01:00 2019 INFO r: 11: Conversations sent after 1316 ms Mon Dec 23 10:40:24 GMT+01:00 2019 INFO p: ICE connection state change to DISCONNECTED Mon Dec 23 10:40:24 GMT+01:00 2019 INFO p: PeerConnectionState changed to CONNECTING Mon Dec 23 10:40:24 GMT+01:00 2019 INFO m: 11: Peer connection state changed from CONNECTED to CONNECTING and signaling state = TASK Mon Dec 23 10:40:32 GMT+01:00 2019 INFO FcmListenerService: Handling incoming FCM intent. Mon Dec 23 10:40:32 GMT+01:00 2019 INFO FcmListenerService: Received FCM message: 0:1577094029558670%f5f551dc5c1930bf Mon Dec 23 10:40:32 GMT+01:00 2019 INFO FcmListenerService: *** Message sent : Mon Dec 23 10:40:29 GMT+01:00 2019 Mon Dec 23 10:40:32 GMT+01:00 2019 INFO FcmListenerService: *** Message received : Mon Dec 23 10:40:32 GMT+01:00 2019 Mon Dec 23 10:40:32 GMT+01:00 2019 INFO FcmListenerService: *** Original priority: 1 Mon Dec 23 10:40:32 GMT+01:00 2019 INFO FcmListenerService: *** Current priority: 1 Mon Dec 23 10:40:32 GMT+01:00 2019 INFO FcmListenerService: Trying to wake up webclient session 1a377e0f2edd10608f70eae60e7472e666efc9c20face580ffc94856569404d8 Mon Dec 23 10:40:32 GMT+01:00 2019 INFO y: startWakeUp Mon Dec 23 10:40:32 GMT+01:00 2019 INFO y: start Mon Dec 23 10:40:32 GMT+01:00 2019 WARN y: Already started, store pending wakeup Mon Dec 23 10:40:34 GMT+01:00 2019 INFO p: ICE connection state change to FAILED Mon Dec 23 10:40:34 GMT+01:00 2019 INFO p: PeerConnectionState changed to FAILED Mon Dec 23 10:40:34 GMT+01:00 2019 INFO m: 11: Peer connection state changed from CONNECTING to FAILED and signaling state = TASK Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/CONNECTED: Disconnecting: DisconnectContext{requestedByUs=false, reason=null} Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTING: Initializing Mon Dec 23 10:40:34 GMT+01:00 2019 INFO B: 11: Session state changed from CONNECTED to DISCONNECTING Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTING: Enter Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTING: Preparing disconnect timer... Mon Dec 23 10:40:34 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 10:40:34 GMT+01:00 2019 INFO m: 11: Signaling state changed to CLOSING Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTING: setDisconnecting - ignoring Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling close request Mon Dec 23 10:40:34 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Closing (remote) Mon Dec 23 10:40:34 GMT+01:00 2019 INFO SaltyRTC.WebRTC.SignalingTransport: Closed (local) Mon Dec 23 10:40:34 GMT+01:00 2019 INFO m: 11: Signaling state changed to CLOSED Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTING: setDisconnected Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Initializing Mon Dec 23 10:40:34 GMT+01:00 2019 INFO B: 11: Session state changed from DISCONNECTING to DISCONNECTED Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Enter Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Cleanup Mon Dec 23 10:40:34 GMT+01:00 2019 INFO p: dispose() Mon Dec 23 10:40:34 GMT+01:00 2019 INFO p: ICE connection state change to CLOSED Mon Dec 23 10:40:34 GMT+01:00 2019 INFO p: PeerConnectionState changed to CLOSED Mon Dec 23 10:40:34 GMT+01:00 2019 INFO p: Signaling state change to CLOSED Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: Data channel saltyrtc-signaling closed Mon Dec 23 10:40:34 GMT+01:00 2019 INFO m: 11: Data channel THREEMA closed Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTED: setDisconnected - ignoring Mon Dec 23 10:40:34 GMT+01:00 2019 INFO m: 11: Peer connection state changed from FAILED to CLOSED and signaling state = CLOSED Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Cleanup done Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Call WebClientServiceListener.onStopped() Mon Dec 23 10:40:34 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Checking for pending wakeups Mon Dec 23 10:40:35 GMT+01:00 2019 INFO y: Process 1 pending wakeups Mon Dec 23 10:40:35 GMT+01:00 2019 INFO r: 11: Resuming Threema Web session Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStopped Mon Dec 23 10:40:35 GMT+01:00 2019 INFO r: 11: Stopping session instance: DisconnectContext{requestedByUs=false, reason=null} Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: setDisconnecting - ignoring Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: sending ACTION_STOP to SessionAndroidService Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: setConnecting Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/NEW: Initializing Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/NEW: Enter Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SessionAndroidService: ACTION_STOP Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/NEW: setConnecting Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SessionAndroidService: ACTION_UPDATE Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SessionAndroidService: No more running sessions Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SessionAndroidService: stopForeground Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SessionAndroidService: Service destroyed Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/CONNECTING: Initializing Mon Dec 23 10:40:35 GMT+01:00 2019 INFO B: 11: Session state changed from DISCONNECTED to CONNECTING Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/CONNECTING: Enter Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/CONNECTING: Starting connection 1 of session 11 Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/CONNECTING: Acquire session resources... Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Connecting to SaltyRTC server at saltyrtc-35.threema.ch:443... Mon Dec 23 10:40:35 GMT+01:00 2019 INFO m: 11: Signaling state changed to WS_CONNECTING Mon Dec 23 10:40:35 GMT+01:00 2019 INFO y: Resumed session 1a377e0f2edd10608f70eae60e7472e666efc9c20face580ffc94856569404d8 from pending wakeup list Mon Dec 23 10:40:35 GMT+01:00 2019 INFO B: 11: Session state changed from CONNECTING to DISCONNECTED Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Enter Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Cleanup Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Cleanup done Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Call WebClientServiceListener.onStopped() Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: Checking for pending wakeups Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStopped Mon Dec 23 10:40:35 GMT+01:00 2019 INFO y: Process 0 pending wakeups Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: setDisconnecting - ignoring Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: updating SessionAndroidService Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: SessionAndroidService not running...not updating Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: SessionAndroidService not running...not stopping Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: updating SessionAndroidService Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: SessionAndroidService not running...not updating Mon Dec 23 10:40:35 GMT+01:00 2019 INFO p: All native resources disposed Mon Dec 23 10:40:35 GMT+01:00 2019 INFO m: 11: Peer connection state changed from CLOSED to CLOSED and signaling state = CLOSED Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/DISCONNECTED: setDisconnecting - ignoring Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SaltyRTC.RSignaling: WebSocket connection established Mon Dec 23 10:40:35 GMT+01:00 2019 INFO m: 11: Signaling state changed to SERVER_HANDSHAKE Mon Dec 23 10:40:35 GMT+01:00 2019 ERROR o: 11/DISCONNECTED: Error: Signaling state changed to SERVER_HANDSHAKE in session state DISCONNECTED Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/ERROR: Initializing Mon Dec 23 10:40:35 GMT+01:00 2019 INFO B: 11: Session state changed from DISCONNECTED to ERROR Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/ERROR: Enter Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/ERROR: Cleanup Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/ERROR: Cleanup done Mon Dec 23 10:40:35 GMT+01:00 2019 INFO ThreemaApplication: WebClientListenerManager: onStateChanged Mon Dec 23 10:40:35 GMT+01:00 2019 INFO m: 11: Signaling state changed to PEER_HANDSHAKE Mon Dec 23 10:40:35 GMT+01:00 2019 ERROR o: 11/ERROR: Error: Signaling state changed to PEER_HANDSHAKE in session state ERROR Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Server handshake done Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Task v1.webrtc.tasks.saltyrtc.org has been selected Mon Dec 23 10:40:35 GMT+01:00 2019 INFO m: 11: Signaling state changed to TASK Mon Dec 23 10:40:35 GMT+01:00 2019 INFO o: 11/ERROR: setHandover - ignoring Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SaltyRTC.RSignaling: Peer handshake done Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: offer Mon Dec 23 10:40:35 GMT+01:00 2019 INFO SaltyRTC.WebRTC.Responder: New task message arrived: candidates Mon Dec 23 10:40:45 GMT+01:00 2019 INFO Bb: releaseConnectionLinger: source = activityPaused, timeout = 60000 Mon Dec 23 10:40:45 GMT+01:00 2019 INFO Bb: Alarm type 1 cancel Mon Dec 23 10:40:45 GMT+01:00 2019 INFO Bb: Alarm type 1 schedule in 59976ms Mon Dec 23 10:40:45 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now paused Mon Dec 23 10:40:45 GMT+01:00 2019 INFO ThreemaApplication: *** Lifecycle: App now hidden Mon Dec 23 10:41:23 GMT+01:00 2019 INFO ia: 0 entries left Mon Dec 23 10:41:23 GMT+01:00 2019 INFO ia: Message payload successfully sent. Size = 24 - Type = 0x0 Mon Dec 23 10:41:23 GMT+01:00 2019 INFO ia: Get payload from SendQueue. Mon Dec 23 10:41:23 GMT+01:00 2019 INFO ma: Received echo reply (seq 2) ```

Your Environment

dbrgn commented 4 years ago

Only local v6 candidates are gathered by Threema Web, no reflexive or remote candidates. Seems to be a duplicate of #946 to me.

ovalseven8 commented 4 years ago

@dbrgn: Note, however, that this is always reproducible for me, no matter whether I've restarted Firefox or not. So, I'm not sure if it's the same underlying issue?

dbrgn commented 4 years ago

I tried to reproduce these steps (set a password, scan, click Menu > Close session, enter password and press enter) in Firefox 71 on Linux, but for me the session always reconnects. If I close the session by pressing F5, reconnecting works as well.

ovalseven8 commented 4 years ago

@dbrgn: Are you using Threema v4.22 Build 2000519 (Google Play)?

Perhaps it has something to do with the local network, but it's always reproducible for me. Perhaps @lgrahl has an idea?

As said above, the red exclamation mark in the app right before Threema Web reaches 15 % is noticeable.

ovalseven8 commented 4 years ago

Now, I managed to get Threema Web connected, so it does not always fail. Still, the reconnection takes more time and is failing for me in most cases.