recording jitter

[newpond]

hi arne, I hope you are well?

have there been any changes in haos that could cause jitter problems with the ha-sip recording function?

I have tested on 2 machines now and in both cases the recording are cutting in and out and totally unusable.

the only change I have made that I can think of (except all the latest haos updates) is switching from Webhooks to mqtt.

running on rpi5 2gb with usb to sata ssd, all cpu usage and temp all normal and plenty of memory spare (although I did also add a swap addon recently to make more available)
thanks in advance

not sure if it helps but various chatgpt troubleshooting came back with this
You are almost certainly hitting a PJMEDIA conference bridge + recorder sink timing bug/regression in HA-SIP v5.6’s underlying stack.
JOn

[newpond]

I have tried taking out the recoding function from ha-sip and done it using ffmpeg locally and the problem still exists
using these commands (which makes me think the problem is

start_call_recorder: >
nohup ffmpeg -f alsa -i hw:Loopback,1,0
-ac 1 -ar 8000 -c:a pcm_s16le
/config/www/Voice_Reports/Carer_1/carer_1_{{ now().strftime('%Y%m%d_%H%M%S') }}.wav
> /config/www/Voice_Reports/Carer_1/recorder.log 2>&1 &

stop_call_recorder: >
pkill -f "ffmpeg -f alsa -i hw:Loopback,1,0"

here's the 500 lines of logs if it helps

please consider the PJ_EINsomeone comments to say PJ_EINVAL (I did a replace on the word val and didnt realise it changed that tooo!!)

16:41:23.112 icetp01 ...Check 3: [2] 192.168.1.62:4040-->192.168.1.37:62843: state changed from Waiting to In Progress
16:41:23.112 icetp01 .Triggered check for check 3 not performed because it's in progress. Retransmitting
16:41:23.112 icetp01 .Triggered check for check 3 not performed because it's in progress. Retransmitting
16:41:23.112 icetp01 Ignoring incoming message for component [2] because source addr 176.31.149.179:17869 unrecognized or unchecked
| 16:41:23.112432 [1] Call is established.
| 16:41:23.112602 [ ] Calling webhook sip_call_webhook_id with data {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'call_established'}
| 16:41:23.123542 [ ] Webhook response 200 b''
| 16:41:23.123767 [ ] Sending mqtt message: {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'call_established'} to topic: hasip/execute
| 16:41:23.134674 [ ] Sensor update sensor.ha_sip_account_1: 200
| 16:41:23.134878 [1] Playing message: Hi, this is "someone's" home, Please press 1 to leave a visitation report now, or press 2 to be called back in 2 hours time
| 16:41:23.135061 [ ] Using cache from file: /config/audio_cache/24e34a677f.wav
16:41:23.135 pjsua_aud.c Creating file player: /config/audio_cache/24e34a677f.wav..
16:41:23.135 wav_player.c .File player '/config/audio_cache/24e34a677f.wav' created: samp.rate=16000, ch=1, bufsize=4KB, filesize=255KB
16:41:23.135 conference.c ..Add port 2 (/config/audio_cache/24e34a677f.wav) queued
16:41:23.135 pjsua_aud.c .Player created, id=0, slot=2
16:41:23.135 pjsua_aud.c Conf connect: 2 --> 1
16:41:23.135 conference.c ..Connect ports 2->1 queued
| 16:41:23.135334 [1] No action supplied
16:41:23.135 icetp01 Starting checklist periodic check
16:41:23.135 icetp01 .Sending connectivity check for check 4: [2] 192.168.1.62:4040-->10.151.139.212:62843
16:41:23.135 icetp01 ..Check 4: [2] 192.168.1.62:4040-->10.151.139.212:62843: state changed from Frozen to In Progress
16:41:23.135 icetp00 Starting checklist periodic check
16:41:23.135 icetp00 .Sending connectivity check for check 4: [2] 192.168.1.62:4020-->10.151.139.212:7235
16:41:23.135 icetp00 ..Check 4: [2] 192.168.1.62:4020-->10.151.139.212:7235: state changed from Frozen to In Progress
16:41:23.135 icetp00 Ignoring incoming message for component [1] because source addr 176.31.149.179:19694 unrecognized or unchecked
16:41:23.135 icetp01 .Error: unable to perform triggered check: TOO MANY CHECKS IN CHECKLIST!
16:41:23.150 conference.c !.Added port 2 (/config/audio_cache/24e34a677f.wav), port count=3
16:41:23.150 conference.c .Port 2 (/config/audio_cache/24e34a677f.wav) transmitting to port 1 (sip:07747******@sip.linphone.org)
16:41:24.136 icetp01 !Starting checklist periodic check
16:41:24.136 icetp01 .Sending connectivity check for check 5: [2] 192.168.1.62:4040-->10.16.44.101:62843
16:41:24.136 icetp01 ..Check 5: [2] 192.168.1.62:4040-->10.16.44.101:62843: state changed from Frozen to In Progress
16:41:24.136 icetp00 Starting checklist periodic check
16:41:24.136 icetp00 .Sending connectivity check for check 5: [2] 192.168.1.62:4020-->10.16.44.101:7235
16:41:24.137 icetp00 ..Check 5: [2] 192.168.1.62:4020-->10.16.44.101:7235: state changed from Frozen to In Progress
16:41:24.137 icetp00 Ignoring incoming message for component [1] because source addr 176.31.149.179:19694 unrecognized or unchecked
16:41:24.137 icetp00 .Check 3: [2] 192.168.1.62:4020-->192.168.1.37:7235 (nominated): connectivity check SUCCESS
16:41:24.137 icetp00 .Check 3: [2] 192.168.1.62:4020-->192.168.1.37:7235: state changed from In Progress to Succeeded
16:41:24.137 icetp00 .Check 3 is successful and nominated
16:41:24.137 icetp00 .Cancelling check 4: [2] 192.168.1.62:4020-->10.151.139.212:7235 (In Progress)
16:41:24.137 icetp00 .Check 4: [2] 192.168.1.62:4020-->10.151.139.212:7235: state changed from In Progress to Failed
16:41:24.137 icetp00 .Cancelling check 5: [2] 192.168.1.62:4020-->10.16.44.101:7235 (In Progress)
16:41:24.137 icetp00 .Check 5: [2] 192.168.1.62:4020-->10.16.44.101:7235: state changed from In Progress to Failed
16:41:24.137 icetp00 .Check 9: [2] 192.168.99.1:4020-->192.168.1.37:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 9: [2] 192.168.99.1:4020-->192.168.1.37:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 10: [2] 192.168.99.1:4020-->10.151.139.212:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 10: [2] 192.168.99.1:4020-->10.151.139.212:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 11: [2] 192.168.99.1:4020-->10.16.44.101:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 11: [2] 192.168.99.1:4020-->10.16.44.101:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 15: [2] 172.30.32.1:4020-->192.168.1.37:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 15: [2] 172.30.32.1:4020-->192.168.1.37:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 16: [2] 172.30.32.1:4020-->10.151.139.212:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 16: [2] 172.30.32.1:4020-->10.151.139.212:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 17: [2] 172.30.32.1:4020-->10.16.44.101:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 17: [2] 172.30.32.1:4020-->10.16.44.101:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 21: [2] 172.30.232.1:4020-->192.168.1.37:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 21: [2] 172.30.232.1:4020-->192.168.1.37:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 22: [2] 172.30.232.1:4020-->10.151.139.212:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 22: [2] 172.30.232.1:4020-->10.151.139.212:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 23: [2] 172.30.232.1:4020-->10.16.44.101:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 23: [2] 172.30.232.1:4020-->10.16.44.101:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 27: [2] 10.21.188.208:4020-->192.168.1.37:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 27: [2] 10.21.188.208:4020-->192.168.1.37:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 28: [2] 10.21.188.208:4020-->10.151.139.212:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 28: [2] 10.21.188.208:4020-->10.151.139.212:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .Check 29: [2] 10.21.188.208:4020-->10.16.44.101:7235 to be failed because state is Frozen
16:41:24.137 icetp00 .Check 29: [2] 10.21.188.208:4020-->10.16.44.101:7235: state changed from Frozen to Failed
16:41:24.137 icetp00 .ICE process complete, status=Success
16:41:24.137 icetp00 .someoneid list
16:41:24.137 icetp00 . 0: [1] 192.168.1.62:4035-->192.168.1.37:7234 (nominated, state=Succeeded)
16:41:24.137 icetp00 . 1: [2] 192.168.1.62:4020-->192.168.1.37:7235 (nominated, state=Succeeded)
16:41:24.137 icetp01 .Check 3: [2] 192.168.1.62:4040-->192.168.1.37:62843 (nominated): connectivity check SUCCESS
16:41:24.137 icetp01 .Check 3: [2] 192.168.1.62:4040-->192.168.1.37:62843: state changed from In Progress to Succeeded
16:41:24.137 icetp01 .Check 3 is successful and nominated
16:41:24.137 icetp01 .Cancelling check 4: [2] 192.168.1.62:4040-->10.151.139.212:62843 (In Progress)
16:41:24.137 icetp01 .Check 4: [2] 192.168.1.62:4040-->10.151.139.212:62843: state changed from In Progress to Failed
16:41:24.137 icetp01 .Cancelling check 5: [2] 192.168.1.62:4040-->10.16.44.101:62843 (In Progress)
16:41:24.137 icetp01 .Check 5: [2] 192.168.1.62:4040-->10.16.44.101:62843: state changed from In Progress to Failed
16:41:24.137 icetp01 .Check 9: [2] 192.168.99.1:4040-->192.168.1.37:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 9: [2] 192.168.99.1:4040-->192.168.1.37:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 10: [2] 192.168.99.1:4040-->10.151.139.212:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 10: [2] 192.168.99.1:4040-->10.151.139.212:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 11: [2] 192.168.99.1:4040-->10.16.44.101:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 11: [2] 192.168.99.1:4040-->10.16.44.101:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 15: [2] 172.30.32.1:4040-->192.168.1.37:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 15: [2] 172.30.32.1:4040-->192.168.1.37:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 16: [2] 172.30.32.1:4040-->10.151.139.212:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 16: [2] 172.30.32.1:4040-->10.151.139.212:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 17: [2] 172.30.32.1:4040-->10.16.44.101:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 17: [2] 172.30.32.1:4040-->10.16.44.101:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 21: [2] 172.30.232.1:4040-->192.168.1.37:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 21: [2] 172.30.232.1:4040-->192.168.1.37:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 22: [2] 172.30.232.1:4040-->10.151.139.212:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 22: [2] 172.30.232.1:4040-->10.151.139.212:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 23: [2] 172.30.232.1:4040-->10.16.44.101:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 23: [2] 172.30.232.1:4040-->10.16.44.101:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 27: [2] 10.21.188.208:4040-->192.168.1.37:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 27: [2] 10.21.188.208:4040-->192.168.1.37:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 28: [2] 10.21.188.208:4040-->10.151.139.212:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 28: [2] 10.21.188.208:4040-->10.151.139.212:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .Check 29: [2] 10.21.188.208:4040-->10.16.44.101:62843 to be failed because state is Frozen
16:41:24.137 icetp01 .Check 29: [2] 10.21.188.208:4040-->10.16.44.101:62843: state changed from Frozen to Failed
16:41:24.137 icetp01 .ICE process complete, status=Success
16:41:24.137 icetp01 .someoneid list
16:41:24.137 icetp01 . 0: [1] 192.168.1.62:4011-->192.168.1.37:55749 (nominated, state=Succeeded)
16:41:24.137 icetp01 . 1: [2] 192.168.1.62:4040-->192.168.1.37:62843 (nominated, state=Succeeded)
16:41:24.137 icetp01 .Ignored incoming check after ICE nego has been completed!
16:41:25.138 icetp00 ICE negotiation success after 3s:031
16:41:25.139 icetp00 Comp 1: sending from host candidate 192.168.1.62:4035 to host candidate 192.168.1.37:7234
16:41:25.139 icetp00 Comp 2: sending from host candidate 192.168.1.62:4020 to host candidate 192.168.1.37:7235
16:41:25.139 pjsua_media.c .Call 3: ICE trickle stopped trickling as ICE nego completed
16:41:25.139 icetp01 ICE negotiation success after 3s:032
16:41:25.139 icetp01 Comp 1: sending from host candidate 192.168.1.62:4011 to host candidate 192.168.1.37:55749
16:41:25.139 icetp01 Comp 2: sending from host candidate 192.168.1.62:4040 to host candidate 192.168.1.37:62843
16:41:25.139 icetp00 Starting checklist periodic check
16:41:25.139 icetp01 Starting checklist periodic check
16:41:25.139 icetp00 Using 192.168.1.37:7234 as someoneid address for component [1]
| 16:41:25.139271 [1] Playback interrupted.
16:41:25.139 pjsua_aud.c .Conf disconnect: 2 -x- 1
16:41:25.139 conference.c ...Disconnect ports 2->1 queued
16:41:25.139 conference.c ..Remove port 2 queued
16:41:25.139 pjsua_aud.c .Destroying player 0..
16:41:25.139 conference.c ...Remove port 2 failed: Insomeoneid someoneue or argument (PJ_EINsomeone)
16:41:25.139 icetp01 Using 192.168.1.37:55749 as someoneid address for component [1]
| 16:41:25.139639 [1] Scheduled post action: noop
16:41:25.150 conference.c !.Port 2 (/config/audio_cache/24e34a677f.wav) stop transmitting to port 1 (sip:07747******@sip.linphone.org)
16:41:25.150 conference.c .Stop any transmission to port 2 (/config/audio_cache/24e34a677f.wav)
16:41:25.150 conference.c .Stop any transmission from port 2 (/config/audio_cache/24e34a677f.wav)
16:41:25.150 conference.c .Removing port 2 (/config/audio_cache/24e34a677f.wav)
16:41:25.150 conference.c .Removed port 2, port count=2
16:41:26.140 pjsua_call.c .Call 3 sending UPDATE for updating ICE transport address after ICE negotiation and media session to use only one codec
16:41:26.141 pjsua_core.c ....TX 1736 bytes Request msg UPDATE/cseq=28911 (tdta0x2fdf4be0) to TCP 176.31.149.179:5060:
UPDATE sip:07747******@sip.linphone.org;gr=urn:uuid:9c3fa***********c231064861d1 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.62:46427;rport;branch=z9hG4bKPjbnuNndydcueqeR5zKQ9p14C8SLZjjILC;alias
Max-Forwards: 70
From: sip:test_alerts@sip.linphone.org;tag=Rm0FdVklph****************
To: sip:07747@sip.linphone.org;tag=yT9bMN6
Contact: sip:test_alerts@86.164.141.30:45764;ob;+sip.ice
Call-ID: AotgB****************7WmkKEpB
CSeq: 28911 UPDATE
Route: sip:sip11.linphone.org:5060;transport=tcp;lr
Route: sips:sip11.linphone.org:5061;lr
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
Content-Type: application/sdp
Content-Length: 1030

v=0
o=- 3993637276 3993637277 IN IP4 192.168.1.62
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4035 RTP/AVP 0 121
c=IN IP4 192.168.1.62
b=TIAS:96000
a=ssrc:1524161090 cname:2faee9694945f47d
a=ice-ufrag:362bee46
a=ice-pwd:2414c6ef3948f8
a=rtcp:4020 IN IP4 192.168.1.62
a=candidate:Hc0a8013e 1 UDP 2130706431 192.168.1.62 4035 typ host
a=candidate:Hc0a8013e 2 UDP 2130706430 192.168.1.62 4020 typ host
a=remote-candidates:1 192.168.1.37 7234 2 192.168.1.37 7235
a=rtpmap:0 PCMU/8000
a=rtpmap:121 telephone-event/8000
a=fmtp:121 0-16
a=sendrecv
m=text 4011 RTP/AVP 100 98
c=IN IP4 192.168.1.62
a=ssrc:1817362614 cname:2faee9694945f47d
a=ice-ufrag:1fee5c5b
a=ice-pwd:080644*****cbd
a=rtcp:4040 IN IP4 192.168.1.62
a=candidate:Hc0a8013e 1 UDP 2130706431 192.168.1.62 4011 typ host
a=candidate:Hc0a8013e 2 UDP 2130706430 192.168.1.62 4040 typ host
a=remote-candidates:1 192.168.1.37 55749 2 192.168.1.37 62843
a=rtpmap:100 red/1000
a=fmtp:100 98/98/98
a=rtpmap:98 t140/1000
a=sendrecv

--end msg--
16:41:26.141 icetp00 Using 192.168.1.37:7235 as someoneid address for component [2]
16:41:26.141 icetp01 Using 192.168.1.37:62843 as someoneid address for component [2]
| 16:41:26.141920 [1] onDtmfDigit: digit 1
| 16:41:26.142016 [ ] Calling webhook sip_call_webhook_id with data {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'dtmf_digit', 'digit': '1'}
| 16:41:26.152515 [ ] Webhook response 200 b''
| 16:41:26.152735 [ ] Sending mqtt message: {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'dtmf_digit', 'digit': '1'} to topic: hasip/execute
| 16:41:26.152977 [1] Current input: 1
| 16:41:26.153042 [ ] Calling webhook sip_call_webhook_id with data {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'entered_menu', 'menu_id': 'record'}
| 16:41:26.162051 [ ] Webhook response 200 b''
| 16:41:26.162268 [ ] Sending mqtt message: {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'entered_menu', 'menu_id': 'record'} to topic: hasip/execute
| 16:41:26.162555 [1] Playing message: Please speak after the tone, please speak slowly and clearly, you may hangup when you have finished.
| 16:41:26.162664 [ ] Using cache from file: /config/audio_cache/c564114697.wav
16:41:26.162 pjsua_aud.c Creating file player: /config/audio_cache/c564114697.wav..
16:41:26.162 wav_player.c .File player '/config/audio_cache/c564114697.wav' created: samp.rate=16000, ch=1, bufsize=4KB, filesize=190KB
16:41:26.162 conference.c ..Add port 2 (/config/audio_cache/c564114697.wav) queued
16:41:26.162 pjsua_aud.c .Player created, id=0, slot=2
16:41:26.162 pjsua_aud.c Conf connect: 2 --> 1
16:41:26.162 conference.c ..Connect ports 2->1 queued
| 16:41:26.162832 [1] No action supplied
16:41:26.162 pjsua_core.c .RX 591 bytes Response msg 407/UPDATE/cseq=28911 (rdata0x2f1d3468) from TCP 176.31.149.179:5060:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/TCP 192.168.1.62:46427;rport=54363;branch=z9hG4bKPjbnuNndydcueqeR5zKQ9p14C8SLZjjILC;alias;received=86.164.141.30
From: sip:test_alerts@sip.linphone.org;tag=Rm0FdVklph****************
To: sip:07747******@sip.linphone.org;tag=yT9bMN6
Call-ID: AotgB7WmkKEpB
CSeq: 28911 UPDATE
Server: Flexisip/2.6.0-beta-11-ge1438f39 (sofia-sip-nta/2.0)
Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="phEK7***YAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth"
Content-Length: 0

--end msg--
16:41:26.163 pjsua_core.c .......TX 2093 bytes Request msg UPDATE/cseq=28912 (tdta0x2fdf4be0) to TCP 176.31.149.179:5060:
UPDATE sip:07747******@sip.linphone.org;gr=urn:uuid:9c3fac231064861d1 SIP/2.0
Via: SIP/2.0/TCP 192.168.1.62:46427;rport;branch=z9hG4bKPj4AH5rnM-aiFyG-CsLpu5a03wmtB9V7Ri;alias
Max-Forwards: 70
From: sip:test_alerts@sip.linphone.org;tag=Rm0FdVklph****************
To: sip:07747@sip.linphone.org;tag=yT9bMN6
Contact: sip:test_alerts@86.164.141.30:45764;ob;+sip.ice
Call-ID: AotgB7WmkKEpB
CSeq: 28912 UPDATE
Route: sip:sip11.linphone.org:5060;transport=tcp;lr
Route: sips:sip11.linphone.org:5061;lr
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
Proxy-Authorization: Digest username="test_alerts", realm="sip.linphone.org", nonce="phEK7YAAAAA", uri="sip:07747@sip.linphone.org;gr=urn:uuid:9c3fa*********c231064861d1", response="ad9a7aecf6***236e9f85cd3", algorithm=MD5, cnonce="W.8UkIrBhK.P47oEBt6ofJeaA", opaque="+GNywA==", qop=auth, nc=00000001
Content-Type: application/sdp
Content-Length: 1030

v=0
o=- 3993637276 3993637277 IN IP4 192.168.1.62
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4035 RTP/AVP 0 121
c=IN IP4 192.168.1.62
b=TIAS:96000
a=ssrc:1524161090 cname:2faee9694945f47d
a=ice-ufrag:362bee46
a=ice-pwd:2414c6ef3948f8
a=rtcp:4020 IN IP4 192.168.1.62
a=candidate:Hc0a8013e 1 UDP 2130706431 192.168.1.62 4035 typ host
a=candidate:Hc0a8013e 2 UDP 2130706430 192.168.1.62 4020 typ host
a=remote-candidates:1 192.168.1.37 7234 2 192.168.1.37 7235
a=rtpmap:0 PCMU/8000
a=rtpmap:121 telephone-event/8000
a=fmtp:121 0-16
a=sendrecv
m=text 4011 RTP/AVP 100 98
c=IN IP4 192.168.1.62
a=ssrc:1817362614 cname:2faee9694945f47d
a=ice-ufrag:1fee5c5b
a=ice-pwd:080644*****cbd
a=rtcp:4040 IN IP4 192.168.1.62
a=candidate:Hc0a8013e 1 UDP 2130706431 192.168.1.62 4011 typ host
a=candidate:Hc0a8013e 2 UDP 2130706430 192.168.1.62 4040 typ host
a=remote-candidates:1 192.168.1.37 55749 2 192.168.1.37 62843
a=rtpmap:100 red/1000
a=fmtp:100 98/98/98
a=rtpmap:98 t140/1000
a=sendrecv

--end msg--
16:41:26.170 conference.c !.Added port 2 (/config/audio_cache/c564114697.wav), port count=3
16:41:26.170 conference.c .Port 2 (/config/audio_cache/c564114697.wav) transmitting to port 1 (sip:07747******@sip.linphone.org)
16:41:27.164 pjsua_core.c .RX 1569 bytes Response msg 200/UPDATE/cseq=28912 (rdata0x2f1d3468) from TCP 176.31.149.179:5060:
SIP/2.0 200 Ok
Via: SIP/2.0/TCP 192.168.1.62:46427;received=86.164.141.30;rport=54363;branch=z9hG4bKPj4AH5rnM-aiFyG-CsLpu5a03wmtB9V7Ri;alias
From: sip:test_alerts@sip.linphone.org;tag=Rm0FdVklph****************
To: sip:07747******@sip.linphone.org;tag=yT9bMN6
Call-ID: AotgB****************7WmkKEpB
CSeq: 28912 UPDATE
User-Agent: LinphoneiOS/6.2.0 (iPhone) LinphoneSDK/5.5.0
Supported: replaces, outbound, gruu, path, record-aware
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE
Contact: sip:07747******@sip.linphone.org;gr=urn:uuid:9c3fa*****************c231064861d1;+sip.instance="urn:uuid:9c3fa*****************c231064861d1";+org.linphone.specs="conference/2.0,ephemeral/1.1,groupchat/1.2,lime"
Content-Type: application/sdp
Record-Route: sip:sip11.linphone.org:5060;transport=tcp;lr
Content-Length: 672

v=0
o=07747****** 3223 905 IN IP4 192.168.1.37
s=Talk
c=IN IP4 192.168.1.37
t=0 0
a=ice-pwd:c4e127cb0*********5416b625f
a=ice-ufrag:014cb5a3
a=Ik:8fc0SKSrUhD4iiSX7CVGkplhqj68QKpEF1pECS/bJxM=
a=lime-Iks:c25519:8fc0SKSrUhD4iiSX7CVGkplhqj68QKpEF1pECS/bJxM=;
m=audio 7234 RTP/AVP 0 121
a=rtpmap:121 telephone-event/8000
a=candidate:1 1 UDP 2130706303 192.168.1.37 7234 typ host
a=candidate:1 2 UDP 2130706302 192.168.1.37 7235 typ host
m=text 55749 RTP/AVP 100 98
a=rtpmap:100 red/1000
a=fmtp:100 98/98/98
a=rtpmap:98 t140/1000
a=rtcp:62843
a=candidate:1 1 UDP 2130706303 192.168.1.37 55749 typ host
a=candidate:1 2 UDP 2130706302 192.168.1.37 62843 typ host

--end msg--
16:41:27.164 inv0x2f1fd800 ....SDP negotiation done: Success
16:41:27.164 pjsua_media.c .....Call 3: updating media..
16:41:27.164 pjsua_media.c ......ICE unchanged, SRTP RX ROC maintained
16:41:27.164 pjsua_media.c ......Call 3: stream #0 (audio) unchanged.
16:41:27.164 icetp00 ......Processing SDP: support ICE=1, common comp_cnt=2, ice_mismatch=0, ice_restart=0, local_role=Controlling, trickle=0
16:41:27.164 icetp00 ......ICE session unchanged
16:41:27.164 pjsua_media.c ......audio updated, stream #0: PCMU (sendrecv)
16:41:27.164 pjsua_media.c ......ICE unchanged, SRTP RX ROC maintained
16:41:27.164 pjsua_txt.c .......Stopping text stream..
16:41:27.164 tstrm0x2f216228 ........Stream destroying
16:41:27.207 tstrm0x2f216228 ........Stream destroyed
16:41:27.207 pjsua_media.c .......Media stream call03:1 is destroyed
16:41:27.207 icetp01 ......Processing SDP: support ICE=1, common comp_cnt=2, ice_mismatch=0, ice_restart=0, local_role=Controlling, trickle=0
16:41:27.207 icetp01 ......ICE session unchanged
16:41:27.207 pjsua_txt.c ......Text channel update for index 1 for call 3...
16:41:27.207 txt_stream.c .......Text stream tstrm0x2f216228 created
16:41:27.207 tstrm0x2f216228 .......Encoder stream started
16:41:27.207 tstrm0x2f216228 .......Decoder stream started
16:41:27.208 pjsua_media.c ......text updated, stream #1: t140 (sendrecv)
| 16:41:27.208075 [1] onCallMediaState call info state 5
| 16:41:27.208174 [1] Connected media 1
| 16:41:32.110709 [1] Playback done.
| 16:41:32.110845 [ ] Calling webhook sip_call_webhook_id with data {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'playback_done', 'type': 'message', 'message': 'Please speak after the tone, please speak slowly and clearly, you may hangup when you have finished.'}
| 16:41:32.121210 [ ] Webhook response 200 b''
| 16:41:32.121467 [ ] Sending mqtt message: {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'playback_done', 'type': 'message', 'message': 'Please speak after the tone, please speak slowly and clearly, you may hangup when you have finished.'} to topic: hasip/execute
16:41:32.121 conference.c .Remove port 2 queued
16:41:32.121 pjsua_aud.c Destroying player 0..
16:41:32.121 conference.c ..Remove port 2 failed: Insomeoneid someoneue or argument (PJ_EINsomeone)
| 16:41:32.122102 [1] Scheduled post action: noop
16:41:32.130 conference.c !.Stop any transmission to port 2 (/config/audio_cache/c564114697.wav)
16:41:32.130 conference.c .Stop any transmission from port 2 (/config/audio_cache/c564114697.wav)
16:41:32.130 conference.c .Port 2 (/config/audio_cache/c564114697.wav) stop transmitting to port 1 (sip:07747******@sip.linphone.org)
16:41:32.130 conference.c .Removing port 2 (/config/audio_cache/c564114697.wav)
16:41:32.130 conference.c .Removed port 2, port count=2
| 16:41:35.125683 [ ] Only post_action "hangup" is supported. Assuming noop.
| 16:41:35.125739 [1] Playing audio file: /config/www/Sound_Files/beep_1.mp3
16:41:35.225 pjsua_aud.c Creating file player: /tmp/tmpffdgl7s2.wav..
16:41:35.225 wav_player.c .File player '/tmp/tmpffdgl7s2.wav' created: samp.rate=44100, ch=1, bufsize=4KB, filesize=87KB
16:41:35.225 conference.c ..Add port 2 (/tmp/tmpffdgl7s2.wav) queued
16:41:35.225 pjsua_aud.c .Player created, id=0, slot=2
16:41:35.226 pjsua_aud.c Conf connect: 2 --> 1
16:41:35.226 conference.c ..Connect ports 2->1 queued
16:41:35.230 conference.c !.Added port 2 (/tmp/tmpffdgl7s2.wav), port count=3
16:41:35.230 conference.c .Port 2 (/tmp/tmpffdgl7s2.wav) transmitting to port 1 (sip:07747******@sip.linphone.org)
| 16:41:36.230725 [1] Playback done.
| 16:41:36.230848 [ ] Calling webhook sip_call_webhook_id with data {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'playback_done', 'type': 'audio_file', 'audio_file': '/config/www/Sound_Files/beep_1.mp3'}
| 16:41:36.242063 [ ] Webhook response 200 b''
| 16:41:36.242278 [ ] Sending mqtt message: {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'playback_done', 'type': 'audio_file', 'audio_file': '/config/www/Sound_Files/beep_1.mp3'} to topic: hasip/execute
16:41:36.242 conference.c .Remove port 2 queued
16:41:36.242 pjsua_aud.c Destroying player 0..
16:41:36.242 conference.c ..Remove port 2 failed: Insomeoneid someoneue or argument (PJ_EINsomeone)
16:41:36.250 conference.c !.Stop any transmission to port 2 (/tmp/tmpffdgl7s2.wav)
16:41:36.250 conference.c .Stop any transmission from port 2 (/tmp/tmpffdgl7s2.wav)
16:41:36.250 conference.c .Port 2 (/tmp/tmpffdgl7s2.wav) stop transmitting to port 1 (sip:07747******@sip.linphone.org)
16:41:36.250 conference.c .Removing port 2 (/tmp/tmpffdgl7s2.wav)
16:41:36.250 conference.c .Removed port 2, port count=2
16:41:37.244 pjsua_aud.c Creating recorder /config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav..
16:41:37.244 wav_writer.c .File writer '/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav' created: samp.rate=16000, bufsize=4KB
16:41:37.244 conference.c ..Add port 2 (/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav) queued
16:41:37.244 pjsua_aud.c .Recorder created, id=0, slot=2
16:41:37.244 pjsua_aud.c Conf connect: 1 --> 2
16:41:37.244 conference.c ..Connect ports 1->2 queued
| 16:41:37.244486 [1] Call recording started: /config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav
| 16:41:37.244586 [ ] Calling webhook sip_call_webhook_id with data {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'recording_started', 'recording_file': '/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav'}
16:41:37.250 conference.c !.Added port 2 (/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav), port count=3
16:41:37.250 conference.c .Port 1 (sip:07747******@sip.linphone.org) transmitting to port 2 (/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav)
16:41:37.250 strm0x2f21dec8 Resetting jitter buffer in stream playback start
| 16:41:37.257994 [ ] Webhook response 200 b''
| 16:41:37.258229 [ ] Sending mqtt message: {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'recording_started', 'recording_file': '/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav'} to topic: hasip/execute
16:41:38.259 icetp00 ..Received Binding Indication keep-alive for component 1
16:41:38.259 icetp00 ..Received Binding Indication keep-alive for component 2
16:41:38.260 icetp01 ..Received Binding Indication keep-alive for component 1
16:41:38.260 icetp01 ..Received Binding Indication keep-alive for component 2
16:41:40.262 pjsua_core.c .RX 540 bytes Request msg BYE/cseq=111 (rdata0x2f1d3468) from TCP 176.31.149.179:5060:
BYE sip:test_alerts@86.164.141.30:54363;ob;transport=tcp SIP/2.0
Via: SIP/2.0/TCP sip11.linphone.org;rport;branch=z9hG4bK.DrDvFcX257Z59eeFD7UX9mjg0H
Via: SIP/2.0/TLS 192.168.1.37:54742;branch=z9hG4bK.nQ1KETyvO;rport=59478;received=86.164.141.30
From: sip:07747******@sip.linphone.org;tag=yT9bMN6
To: sip:test_alerts@sip.linphone.org;tag=Rm0FdVklph****************
CSeq: 111 BYE
Call-ID: AotgB****************7WmkKEpB
Max-Forwards: 69
User-Agent: LinphoneiOS/6.2.0 (iPhone) LinphoneSDK/5.5.0
Content-Length: 0

--end msg--
16:41:40.262 pjsua_core.c .......TX 441 bytes Response msg 200/BYE/cseq=111 (tdta0x2fdf2020) to TCP 176.31.149.179:5060:
SIP/2.0 200 OK
Via: SIP/2.0/TCP sip11.linphone.org;rport=5060;received=176.31.149.179;branch=z9hG4bK.DrDvFcX257Z59eeFD7UX9mjg0H
Via: SIP/2.0/TLS 192.168.1.37:54742;rport=59478;received=86.164.141.30;branch=z9hG4bK.nQ1KETyvO
Call-ID: AotgB7WmkKEpB
From: sip:07747******@sip.linphone.org;tag=yT9bMN6
To: sip:test_alerts@sip.linphone.org;tag=Rm0FdVklph
CSeq: 111 BYE
Content-Length: 0

--end msg--
16:41:40.262 pjsua_media.c ......Call 3: deinitializing media..
16:41:40.262 pjsua_media.c .......
[DISCONNECTED] To: sip:07747******@sip.linphone.org;tag=yT9bMN6
Call time: 00h:00m:18s, 1st res in 6055 ms, conn in 6058ms
#0 audio PCMU @8khz, sendrecv, peer=192.168.1.37:7234
SRTP status: Not active Crypto-suite:
ICE role: Controlling, state: Negotiation Success, comp_cnt: 2
[0]: L:192.168.1.62:4035 (h) --> R:192.168.1.37:7234 (h)
[1]: L:192.168.1.62:4020 (h) --> R:192.168.1.37:7235 (h)
RX pt=0, last update:00h:00m:02.612s ago
total 789pkt 134.5KB (166.1KB +IP hdr) @AvG=59.3Kbps/73.2Kbps
pkt loss=0 (0.0%), discrd=0 (0.0%), dup=2 (0.3%), reord=0 (0.0%)
(msec) min avg max last dev
loss period: 0.000 0.000 0.000 0.000 0.000
jitter : 1.250 40.836 108.625 60.125 18.319
TX pt=0, ptime=20, last update:00h:00m:03.018s ago
total 545pkt 87.2KB (109.0KB +IP hdr) @AvG=38.4Kbps/48.0Kbps
pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%)
(msec) min avg max last dev
loss period: 0.000 0.000 0.000 0.000 0.000
jitter : 0.000 0.000 0.000 0.000 0.000
RTT msec : 220.000 515.167 754.000 513.000 35.814
#1 text t140, sendrecv, peer=192.168.1.37:55749
SRTP status: Not active Crypto-suite:
ICE role: Controlling, state: Negotiation Success, comp_cnt: 2
[0]: L:192.168.1.62:4011 (h) --> R:192.168.1.37:55749 (h)
[1]: L:192.168.1.62:4040 (h) --> R:192.168.1.37:62843 (h)
RX pt=98, red=100(lvl=2) last update:00h:00m:02.555s ago
total 43pkt 516B (2.2KB +IP hdr) @AvG=316bps/1.3Kbps
pkt loss=0 (0.0%), discrd=0 (0.0%), dup=0 (0.0%), reord=0 (0.0%)
(msec) min avg max last dev
loss period: 0.000 0.000 0.000 0.000 0.000
jitter : 50.000 179.353 278.000 278.000 41.300
TX pt=98, red=100(lvl=2), last update:never
total 0pkt 0B (0B +IP hdr) @AvG=0bps/0bps
pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%)
(msec) min avg max last dev
loss period: 0.000 0.000 0.000 0.000 0.000
jitter : 0.000 0.000 0.000 0.000 0.000
RTT msec : 0.000 0.000 0.000 0.000 0.000
16:41:40.262 conference.c .........Remove port 1 queued
16:41:40.262 strm0x2f21dec8 ........Stream destroying
16:41:40.262 pjsua_media.c ........Media stream call03:0 is destroyed
16:41:40.262 pjsua_txt.c ........Stopping text stream..
16:41:40.262 tstrm0x2f216228 .........Stream destroying
16:41:40.270 conference.c !.Stop any transmission to port 1 (sip:07747******@sip.linphone.org)
16:41:40.270 conference.c .Stop any transmission from port 1 (sip:07747******@sip.linphone.org)
16:41:40.270 conference.c .Port 1 (sip:07747******@sip.linphone.org) stop transmitting to port 2 (/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav)
16:41:40.270 conference.c .Removing port 1 (sip:07747******@sip.linphone.org)
16:41:40.270 conference.c .Removed port 1, port count=2
16:41:40.270 strm0x2f21dec8 .Stream destroyed
16:41:40.408 tstrm0x2f216228 .........Stream destroyed
16:41:40.408 pjsua_media.c ........Media stream call03:1 is destroyed
16:41:40.408 icetp00 .......Stopping ICE, reason=media stop requested
16:41:40.408 icetp00 .......Destroying ICE session 0x2fdeeb18
16:41:40.408 srtp0x2fe04300 .......Destroying SRTP transport
16:41:40.408 icetp00 .......Destroying ICE transport
16:41:40.408 ice_session.c .......ICE session 0x2fdeeb18 destroyed
16:41:40.408 icetp00 .......ICE stream transport 0x2fde9e88 destroyed
16:41:40.408 icetp00 .......ICE transport destroyed
16:41:40.408 srtp0x2fe04300 .......SRTP transport destroyed
16:41:40.408 icetp01 .......Stopping ICE, reason=media stop requested
16:41:40.408 icetp01 .......Destroying ICE session 0x2f2045f8
16:41:40.408 srtp0x2fe05aa0 .......Destroying SRTP transport
16:41:40.408 icetp01 .......Destroying ICE transport
16:41:40.408 ice_session.c .......ICE session 0x2f2045f8 destroyed
16:41:40.408 icetp01 .......ICE stream transport 0x2f220a38 destroyed
16:41:40.408 icetp01 .......ICE transport destroyed
16:41:40.408 srtp0x2fe05aa0 .......SRTP transport destroyed
| 16:41:40.408815 [1] Call disconnected
16:41:40.408 pjsua_aud.c ......Conf disconnect: 1 -x- 2
16:41:40.408 conference.c ........Disconnect ports 1->2 failed: Insomeoneid someoneue or argument (PJ_EINsomeone)
16:41:40.408 media.cpp ......pjsua_conf_disconnect(id, sink.id) error: Insomeoneid someoneue or argument (PJ_EINsomeone) (status=70004) [../src/pjsua2/media.cpp:251]
| 16:41:40.408966 [1] Error: Failed to stop call recording:
| 16:41:40.408999 [1] Call recording stopped: /config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav
| 16:41:40.409057 [ ] Calling webhook sip_call_webhook_id with data {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'recording_stopped', 'recording_file': '/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav'}
| 16:41:40.419300 [ ] Webhook response 200 b''
| 16:41:40.419519 [ ] Sending mqtt message: {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'recording_stopped', 'recording_file': '/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav'} to topic: hasip/execute
16:41:40.419 conference.c .......Remove port 2 queued
16:41:40.419 pjsua_aud.c ......Destroying recorder 0..
16:41:40.419 conference.c ........Remove port 2 failed: Insomeoneid someoneue or argument (PJ_EINsomeone)
| 16:41:40.419866 [ ] Calling webhook sip_call_webhook_id with data {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'call_disconnected'}
| 16:41:40.429860 [ ] Webhook response 200 b''
| 16:41:40.430084 [ ] Sending mqtt message: {'local_uri': 'sip:test_alerts@sip.linphone.org', 'remote_uri': 'sip:07747******@sip.linphone.org', 'parsed_local_uri': 'test_alerts', 'parsed_remote_uri': '07747******', 'sip_account': 1, 'call_id': 'AotgB7WmkKEpB', 'internal_id': 'sip:07747@sip.linphone.org', 'headers': {}, 'call_direction': 'outgoing', 'caller': 'sip:07747@sip.linphone.org', 'called': 'sip:test_alerts@sip.linphone.org', 'parsed_caller': '07747**', 'parsed_called': 'test_alerts', 'event': 'call_disconnected'} to topic: hasip/execute
16:41:40.430 conference.c !.Stop any transmission to port 2 (/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav)
16:41:40.430 conference.c .Stop any transmission from port 2 (/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav)
16:41:40.430 conference.c .Removing port 2 (/config/www/Voice_Reports/Carer_1/carer_1_21072026_1641.wav)
16:41:40.430 conference.c .Removed port 2, port count=1
| 16:41:40.440361 [ ] Sensor update sensor.ha_sip_account_1: 200
| 16:41:40.451105 [ ] Sensor update sensor.ha_sip_last_call_1: 200
| 16:41:40.451314 [ ] Remove from state: sip:07747******@sip.linphone.org
16:41:41.462 pjsua_aud.c Closing sound device after idle for 1 second(s)
16:41:41.462 pjsua_aud.c .Closing null sound device..
16:42:13.826 sip_transport.c Transport tcpc0x2f1d3178 is being destroyed due to timeout in idle timer
16:42:13.826 tcpc0x2f1d3178 TCP transport destroyed normally

cheers

JOn