Closed ivelin closed 4 years ago
@muka Let's use this issue to track down the 500 with HTML stack trace. @BKristenssonAlfsson can you please attach full log with 1000 lines before and after the 500 exception. Also please state the version of the Ambianic edge docker image you are running.
Related to ambianic/ambianic-ui/issues/393
2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.
Starting http-proxy over webrtc. <<<< 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py._start(317): Calling make_discoverable 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.make_discoverable(274): Peer destroyed. Will create a new peer. 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pnp_service_connect(239): creating peer 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pnp_service_connect(244): last saved savedPeerId 5568ec87-42d8-47b0-aeea-01a125db0623 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pnp_service_connect(246): Peer session token 7d8b968c-c8bf-4b0d-86a2-dba92107d24d 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pnp_service_connect(256): pnpService: peer created with id 5568ec87-42d8-47b0-aeea-01a125db0623 , options: PeerOptions(host='ambianic-pnp.herokuapp.com', port=443, path='/', key='peerjs', token='7d8b968c-c8bf-4b0d-86a2-dba92107d24d', config=RTCConfiguration(iceServers=[RTCIceServer(urls=['stun:stun1.l.google.com:19302', 'stun:stun2.l.google.com:19302', 'stun:stun3.l.google.com:19302', 'stun:stun4.l.google.com:19302', 'stun:stun.l.google.com:19302', 'stun:stun.services.mozilla.com:3478', 'stun:stunserver.org:3478'], username=None, credential=None, credentialType='password'), RTCIceServer(urls=['turn:numb.viagenie.ca'], username='webrtc@live.com', credential='muazkh', credentialType='password'), RTCIceServer(urls=['turn:192.158.29.39:3478?transport=udp'], username='28224511:1379330808', credential='JZEOEt2V3Qb0y27GRntt2u2PAYA=', credentialType='password'), RTCIceServer(urls=['turn:192.158.29.39:3478?transport=tcp'], username='28224511:1379330808', credential='JZEOEt2V3Qb0y27GRntt2u2PAYA=', credentialType='password'), RTCIceServer(urls=['turn:turn.bistri.com:80'], username='homeo', credential='homeo', credentialType='password'), RTCIceServer(urls=['turn:turn.anyfirewall.com:443?transport=tcp'], username='webrtc', credential='webrtc', credentialType='password'), RTCIceServer(urls=['turn:0.peerjs.com:3478'], username='peerjs', credential='peerjsp', credentialType='password')]), secure=True, pingInterval=5) 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/peer.py.start(118): Peer started with UUID: 5568ec87-42d8-47b0-aeea-01a125db0623 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pnp_service_connect(258): peer activated 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/peer.py._on_server_open(219): Signaling server connection open. 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.peer_open(91): Peer signaling connection open. 2020-06-02 14:14:53 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.peer_open(105): savedPeerId: 5568ec87-42d8-47b0-aeea-01a125db0623 Starting server... Configuring server... Secrets file not found. Proceeding without it: /workspace/secrets.yaml Configuration file not found: /workspace/config.yaml Please provide a configuration file and restart. 2020-06-02 14:14:56 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/peerjs/negotiator.py._makeAnswer(266): Gathering ICE candidates to complete answer... 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/peerjs/negotiator.py._makeAnswer(266): Gathering ICE candidates to complete answer... 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/turn.py.connect(100): TURN allocation created ('158.69.221.198', 53161) 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) Check CandidatePair(('172.31.27.2', 42692) -> ('2.249.141.112', 58277)) State.FROZEN -> State.WAITING 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.__log_info(1008): Connection(1) Check CandidatePair(('172.17.0.1', 37477) -> ('2.249.141.112', 58277)) State.FROZEN -> State.WAITING 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) Check CandidatePair(('172.19.0.1', 51814) -> ('2.249.141.112', 58277)) State.FROZEN -> State.WAITING 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) Check CandidatePair(('158.69.221.198', 53161) -> ('2.249.141.112', 58277)) State.FROZEN -> State.WAITING 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.peer_connection(137): Remote peer trying to establish connection 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) Check CandidatePair(('172.31.27.2', 42692) -> ('2.249.141.112', 58277)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) Check CandidatePair(('172.17.0.1', 37477) -> ('2.249.141.112', 58277)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) Check CandidatePair(('172.19.0.1', 51814) -> ('2.249.141.112', 58277)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:14:58 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) Check CandidatePair(('158.69.221.198', 53161) -> ('2.249.141.112', 58277)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:14:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/turn.py.channel_bind(71): TURN channel bound 16384 ('2.249.141.112', 58277) 2020-06-02 14:14:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) Check CandidatePair(('172.31.27.2', 42692) -> ('2.249.141.112', 58277)) State.IN_PROGRESS -> State.SUCCEEDED 2020-06-02 14:14:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(1) ICE completed 2020-06-02 14:14:59 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:14:59 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_open(181): Connected to: 85047944-6bf8-4f9e-941f-2b6cd0c4c0f4 2020-06-02 14:15:01 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_data(193): webrtc peer: http proxy request: {'url': 'http://localhost:8778?', 'method': 'GET'} 2020-06-02 14:15:01 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py._ping(172): webrtc peer: http proxy response ping. Keeping datachannel alive. 127.0.0.1 - - [02/Jun/2020 14:15:01] "GET / HTTP/1.1" 200 - 2020-06-02 14:15:01 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_data(223): Proxy fetched response with headers: <CIMultiDictProxy('Content-Type': 'text/html; charset=utf-8', 'Content-Length': '59', 'Access-Control-Allow-Origin': '*', 'Server': 'Werkzeug/1.0.1 Python/3.7.3', 'Date': 'Tue, 02 Jun 2020 14:15:01 GMT')> 2020-06-02 14:15:01 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_data(226): Answering request: {'url': 'http://localhost:8778?', 'method': 'GET'} response header: {'status': 200, 'content-type': 'text/html; charset=utf-8', 'content-length': 59} 2020-06-02 14:15:02 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:05 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:08 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:11 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:14 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:17 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/peerjs/negotiator.py._makeAnswer(266): Gathering ICE candidates to complete answer... 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/turn.py.connect(100): TURN allocation created ('158.69.221.198', 58250) 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Check CandidatePair(('172.31.27.2', 39535) -> ('136.49.233.5', 60982)) State.FROZEN -> State.WAITING 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Check CandidatePair(('172.17.0.1', 50653) -> ('136.49.233.5', 60982)) State.FROZEN -> State.WAITING 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.__log_info(1008): Connection(2) Check CandidatePair(('172.19.0.1', 38540) -> ('136.49.233.5', 60982)) State.FROZEN -> State.WAITING 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Check CandidatePair(('158.69.221.198', 58250) -> ('136.49.233.5', 60982)) State.FROZEN -> State.WAITING 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.peer_connection(137): Remote peer trying to establish connection 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Check CandidatePair(('172.31.27.2', 39535) -> ('136.49.233.5', 60982)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Check CandidatePair(('172.17.0.1', 50653) -> ('136.49.233.5', 60982)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Check CandidatePair(('172.19.0.1', 38540) -> ('136.49.233.5', 60982)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Check CandidatePair(('158.69.221.198', 58250) -> ('136.49.233.5', 60982)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Check CandidatePair(('172.31.27.2', 39535) -> ('136.49.233.5', 60982)) State.IN_PROGRESS -> State.SUCCEEDED 2020-06-02 14:15:19 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) ICE completed 2020-06-02 14:15:20 INFO /usr/local/lib/python3.7/dist-packages/aioice/turn.py.channel_bind(71): TURN channel bound 16384 ('136.49.233.5', 60982) 2020-06-02 14:15:20 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_open(181): Connected to: bcbf148d-272c-48d3-9ee6-453b2fd65813 2020-06-02 14:15:20 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/peerjs/negotiator.py._makeAnswer(266): Gathering ICE candidates to complete answer... 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/turn.py.connect(100): TURN allocation created ('158.69.221.198', 58576) 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) Check CandidatePair(('172.31.27.2', 51992) -> ('136.49.233.5', 54385)) State.FROZEN -> State.WAITING 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.__log_info(1008): Connection(3) Check CandidatePair(('172.17.0.1', 37121) -> ('136.49.233.5', 54385)) State.FROZEN -> State.WAITING 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) Check CandidatePair(('172.19.0.1', 41748) -> ('136.49.233.5', 54385)) State.FROZEN -> State.WAITING 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) Check CandidatePair(('158.69.221.198', 58576) -> ('136.49.233.5', 54385)) State.FROZEN -> State.WAITING 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.peer_connection(137): Remote peer trying to establish connection 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) Check CandidatePair(('172.31.27.2', 51992) -> ('136.49.233.5', 54385)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) Check CandidatePair(('172.17.0.1', 37121) -> ('136.49.233.5', 54385)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) Check CandidatePair(('172.19.0.1', 41748) -> ('136.49.233.5', 54385)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) Check CandidatePair(('158.69.221.198', 58576) -> ('136.49.233.5', 54385)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/turn.py.channel_bind(71): TURN channel bound 16384 ('136.49.233.5', 54385) 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) Check CandidatePair(('172.31.27.2', 51992) -> ('136.49.233.5', 54385)) State.IN_PROGRESS -> State.SUCCEEDED 2020-06-02 14:15:22 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(3) ICE completed 2020-06-02 14:15:23 ERROR /usr/lib/python3.7/asyncio/base_events.py.default_exception_handler(1608): Task exception was never retrieved future: <Task finished coro=<RTCSctpTransport._transmit() done, defined at /usr/local/lib/python3.7/dist-packages/aiortc/rtcsctptransport.py:1505> exception=ConnectionError('Cannot send encrypted data, not connected')> Traceback (most recent call last): File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcsctptransport.py", line 1539, in _transmit await self._send_chunk(chunk) File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcsctptransport.py", line 1344, in _send_chunk chunk, File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcdtlstransport.py", line 655, in _send_data raise ConnectionError("Cannot send encrypted data, not connected") ConnectionError: Cannot send encrypted data, not connected 2020-06-02 14:15:23 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_open(181): Connected to: bcbf148d-272c-48d3-9ee6-453b2fd65813 2020-06-02 14:15:23 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:26 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_data(193): webrtc peer: http proxy request: {'url': 'http://localhost:8778?', 'method': 'GET'} 2020-06-02 14:15:26 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py._ping(172): webrtc peer: http proxy response ping. Keeping datachannel alive. 127.0.0.1 - - [02/Jun/2020 14:15:26] "GET / HTTP/1.1" 200 - 2020-06-02 14:15:26 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_data(223): Proxy fetched response with headers: <CIMultiDictProxy('Content-Type': 'text/html; charset=utf-8', 'Content-Length': '59', 'Access-Control-Allow-Origin': '*', 'Server': 'Werkzeug/1.0.1 Python/3.7.3', 'Date': 'Tue, 02 Jun 2020 14:15:26 GMT')> 2020-06-02 14:15:26 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_data(226): Answering request: {'url': 'http://localhost:8778?', 'method': 'GET'} response header: {'status': 200, 'content-type': 'text/html; charset=utf-8', 'content-length': 59} 2020-06-02 14:15:27 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:30 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:33 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:36 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:39 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:42 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:45 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:48 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:51 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(2) Consent to send expired 2020-06-02 14:15:51 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:51 INFO /usr/local/lib/python3.7/dist-packages/aioice/turn.py.delete(153): TURN allocation deleted ('158.69.221.198', 58250) 2020-06-02 14:15:54 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:57 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/peerjs/negotiator.py._makeAnswer(266): Gathering ICE candidates to complete answer... 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/turn.py.connect(100): TURN allocation created ('158.69.221.198', 52740) 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) Check CandidatePair(('172.31.27.2', 45300) -> ('136.49.233.5', 53080)) State.FROZEN -> State.WAITING 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.__log_info(1008): Connection(4) Check CandidatePair(('172.17.0.1', 55623) -> ('136.49.233.5', 53080)) State.FROZEN -> State.WAITING 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) Check CandidatePair(('172.19.0.1', 42150) -> ('136.49.233.5', 53080)) State.FROZEN -> State.WAITING 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) Check CandidatePair(('158.69.221.198', 52740) -> ('136.49.233.5', 53080)) State.FROZEN -> State.WAITING 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.peer_connection(137): Remote peer trying to establish connection 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) Check CandidatePair(('172.31.27.2', 45300) -> ('136.49.233.5', 53080)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) Check CandidatePair(('172.17.0.1', 55623) -> ('136.49.233.5', 53080)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) Check CandidatePair(('172.19.0.1', 42150) -> ('136.49.233.5', 53080)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:59 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) Check CandidatePair(('158.69.221.198', 52740) -> ('136.49.233.5', 53080)) State.WAITING -> State.IN_PROGRESS 2020-06-02 14:15:59 ERROR /usr/lib/python3.7/asyncio/base_events.py.default_exception_handler(1608): Task exception was never retrieved future: <Task finished coro=<RTCSctpTransport._transmit() done, defined at /usr/local/lib/python3.7/dist-packages/aiortc/rtcsctptransport.py:1505> exception=ConnectionError('Cannot send encrypted data, not connected')> Traceback (most recent call last): File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcsctptransport.py", line 1539, in _transmit await self._send_chunk(chunk) File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcsctptransport.py", line 1344, in _send_chunk chunk, File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcdtlstransport.py", line 655, in _send_data raise ConnectionError("Cannot send encrypted data, not connected") ConnectionError: Cannot send encrypted data, not connected 2020-06-02 14:16:00 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) Check CandidatePair(('172.31.27.2', 45300) -> ('136.49.233.5', 53080)) State.IN_PROGRESS -> State.SUCCEEDED 2020-06-02 14:16:00 INFO /usr/local/lib/python3.7/dist-packages/aioice/ice.py.log_info(1008): Connection(4) ICE completed 2020-06-02 14:16:00 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.pc_open(181): Connected to: bcbf148d-272c-48d3-9ee6-453b2fd65813 2020-06-02 14:16:00 INFO /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.join_peer_room(64): myRoom members {'clientsIds': ['5568ec87-42d8-47b0-aeea-01a125db0623']} 2020-06-02 14:16:01 WARNING /usr/local/lib/python3.7/dist-packages/peerjs/peer.py.emitError(464): Connection error: STUN transaction timed out 2020-06-02 14:16:01 WARNING /usr/local/lib/python3.7/dist-packages/peerjs/peer.py.emitError(469): Connection error: STUN transaction timed out 2020-06-02 14:16:01 ERROR /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.peer_error(128): Peer error STUN transaction timed out Traceback (most recent call last): File "/usr/local/lib/python3.7/dist-packages/peerjs/negotiator.py", line 267, in _makeAnswer await peerConnection.setLocalDescription(answer) File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcpeerconnection.py", line 724, in setLocalDescription await self.gather() File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcpeerconnection.py", line 946, in gather await asyncio.gather(*coros) File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcicetransport.py", line 197, in gather await self._connection.gather_candidates() File "/usr/local/lib/python3.7/dist-packages/aioice/ice.py", line 393, in gather_candidates component=component, addresses=addresses File "/usr/local/lib/python3.7/dist-packages/aioice/ice.py", line 837, in get_component_candidates transport=self.turn_transport, File "/usr/local/lib/python3.7/dist-packages/aioice/turn.py", line 324, in create_turn_endpoint await turn_transport._connect() File "/usr/local/lib/python3.7/dist-packages/aioice/turn.py", line 288, in _connect self.relayed_address = await self.innerprotocol.connect() File "/usr/local/lib/python3.7/dist-packages/aioice/turn.py", line 84, in connect response, = await self.request(request) File "/usr/local/lib/python3.7/dist-packages/aioice/turn.py", line 186, in request return await transaction.run() File "/usr/local/lib/python3.7/dist-packages/aioice/stun.py", line 292, in run return await self.future aioice.stun.TransactionTimeout: STUN transaction timed out 2020-06-02 14:16:01 WARNING /usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py.peer_error(129): peerConnectionStatus None --- Logging error --- Traceback (most recent call last): File "/usr/local/lib/python3.7/dist-packages/peerjs/negotiator.py", line 267, in _makeAnswer await peerConnection.setLocalDescription(answer) File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcpeerconnection.py", line 724, in setLocalDescription await self.gather() File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcpeerconnection.py", line 946, in gather await asyncio.gather(*coros) File "/usr/local/lib/python3.7/dist-packages/aiortc/rtcicetransport.py", line 197, in gather await self._connection.gather_candidates() File "/usr/local/lib/python3.7/dist-packages/aioice/ice.py", line 393, in gather_candidates component=component, addresses=addresses File "/usr/local/lib/python3.7/dist-packages/aioice/ice.py", line 837, in get_component_candidates transport=self.turn_transport, File "/usr/local/lib/python3.7/dist-packages/aioice/turn.py", line 324, in create_turn_endpoint await turn_transport._connect() File "/usr/local/lib/python3.7/dist-packages/aioice/turn.py", line 288, in _connect self.__relayed_address = await self.innerprotocol.connect() File "/usr/local/lib/python3.7/dist-packages/aioice/turn.py", line 84, in connect response, = await self.request(request) File "/usr/local/lib/python3.7/dist-packages/aioice/turn.py", line 186, in request return await transaction.run() File "/usr/local/lib/python3.7/dist-packages/aioice/stun.py", line 292, in run return await self.future aioice.stun.TransactionTimeout: STUN transaction timed out
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/usr/lib/python3.7/logging/init.py", line 1034, in emit
msg = self.format(record)
File "/usr/lib/python3.7/logging/init.py", line 880, in format
return fmt.format(record)
File "/usr/local/lib/python3.7/dist-packages/coloredlogs/init.py", line 1115, in format
return logging.Formatter.format(self, record)
File "/usr/lib/python3.7/logging/init.py", line 619, in format
record.message = record.getMessage()
File "/usr/lib/python3.7/logging/init.py", line 380, in getMessage
msg = msg % self.args
TypeError: not all arguments converted during string formatting
Call stack:
File "/usr/lib/python3.7/runpy.py", line 193, in _run_module_as_main
"main", mod_spec)
File "/usr/lib/python3.7/runpy.py", line 85, in _run_code
exec(code, run_globals)
File "/usr/local/lib/python3.7/dist-packages/peerjs/ext/http-proxy.py", line 362, in
Starting the Edge with command:
docker run -it --rm --name ambianic-edge --mount type=bind,source=$PWD,target=/workspace --net=host ambianic/ambianic-edge
Thank you. Which version of the ambianic-edge docker image are you running?
Looks like the exception is thrown here:
File "/opt/ambianic-edge/src/ambianic/webapp/server/samples.py", line 154, in get_timeline
with p.open() as pf:
@BKristenssonAlfsson Line 154 in samples.py is different in the new version from @muka which handles file errors better. Can you please pull the latest docker image. If you run docker-compose with the latest docker-config.yaml from the quickstart guide it will automatically pull new ambianic-edge images when available.
In the current version we read a file only if it is available on the file-system. I would close this issue as fixed
@BKristenssonAlfsson is running edge on EC2 which appears to function well. UI can connect to it. However the edge REST API returns 500 with HTML instead of properly formatted JSON with an actionable error message for the user.