freeswitch icon indicating copy to clipboard operation
freeswitch copied to clipboard

the channels often not destroyed

Open infook-liu opened this issue 3 years ago • 3 comments
trafficstars

Freeswitch version 1.10.7 (the latest version of Git is the same). ESL connection control is adopted. When the call is connected, if uuid_record is sent to record and send uuid_kill immediately to hangs up. In this case, the channels often not destroyed. You can see it in "show channels".

Trace code discovery, recording_thread does not exit (the code position is not fixed), so the state machine cannot do destroy. Whether there are such problems, thank you!

infook-liu avatar Jun 01 '22 12:06 infook-liu

log: 2022-06-01 20:18:47.269469 98.83% [NOTICE] mod_commands.c:3867 Channel [sofia/external/[email protected]:15580] has been answered 2022-06-01 20:18:47.269469 98.83% [DEBUG] switch_channel.c:3950 (sofia/external/[email protected]:15580) Callstate Change EARLY -> ACTIVE 2022-06-01 20:18:47.269469 98.83% [DEBUG] sofia.c:7499 Channel sofia/external/[email protected]:15580 entering state [completed][200] 2022-06-01 20:18:47.289464 98.83% [DEBUG] sofia.c:7499 Channel sofia/external/[email protected]:15580 entering state [ready][200]

2022-06-01 20:18:47.369468 98.83% [NOTICE] switch_ivr.c:4403 Hangup sofia/external/[email protected]:15580 [CS_EXECUTE] [NORMAL_CLEARING] 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_session.c:2983 sofia/external/[email protected]:15580 skip receive message [PHONE_EVENT] (channel is hungup already) 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:647 (sofia/external/[email protected]:15580) State EXECUTE going to sleep 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/external/[email protected]:15580) Running State Change CS_HANGUP (Cur 2 Tot 8) 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:844 (sofia/external/[email protected]:15580) Callstate Change ACTIVE -> HANGUP 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:846 (sofia/external/[email protected]:15580) State HANGUP 2022-06-01 20:18:47.369468 98.83% [DEBUG] mod_sofia.c:468 Channel sofia/external/[email protected]:15580 hanging up, cause: NORMAL_CLEARING 2022-06-01 20:18:47.369468 98.83% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/external/[email protected]:15580 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:59 sofia/external/[email protected]:15580 Standard HANGUP, cause: NORMAL_CLEARING 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:846 (sofia/external/[email protected]:15580) State HANGUP going to sleep 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:616 (sofia/external/[email protected]:15580) State Change CS_HANGUP -> CS_REPORTING 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:581 (sofia/external/[email protected]:15580) Running State Change CS_REPORTING (Cur 2 Tot 8) 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:932 (sofia/external/[email protected]:15580) State REPORTING 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:168 sofia/external/[email protected]:15580 Standard REPORTING, cause: NORMAL_CLEARING 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:932 (sofia/external/[email protected]:15580) State REPORTING going to sleep 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_state_machine.c:607 (sofia/external/[email protected]:15580) State Change CS_REPORTING -> CS_DESTROY 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_session.c:1753 Session 8 (sofia/external/[email protected]:15580) Locked, Waiting on external entities 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000 2022-06-01 20:18:47.369468 98.83% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/external/[email protected]:15580

infook-liu avatar Jun 01 '22 12:06 infook-liu

Can you attach a test case that can be ran to recreate this on demand?

briankwest avatar Jun 01 '22 15:06 briankwest

Sorry, I don't have a demo program. The program I tested needs other environments and can't be run directly!

After testing, Using "bgapi uuid_record ..." will have problems, but using "api uuid_record ..." is normal.

infook-liu avatar Jun 02 '22 06:06 infook-liu

I ran into the same issue and i can repeat the case.It's a lock issue.

hangup function call list: 1、switch_channel_perform_hangup 2、switch_core_session_thread_launch 3、switch_thread_create 4、switch_core_session_thread 5、switch_core_media_bug_remove_all 6、switch_core_session_write_lock

uuid_record function call list: 1、switch_ivr_record_session_event 2、switch_core_media_bug_add 3、switch_thread_rwlock_wrlock

repeat cause: when after switch_core_media_bug_remove_all(hangup function call list) call switch_core_media_bug_add(uuid_record function call list),will be block at switch_core_session_write_lock(hangup function call list)

406112767 avatar Nov 29 '22 03:11 406112767

@406112767 Can you provide us the steps on how to reproduce it?

greenbea avatar Nov 29 '22 03:11 greenbea

@greenbea I can't reproduce the issue 100%, but there is a good chance that I will reproduce the problem by following the steps below in caller, I use ESL connection listen CHANNEL_PROGRESS_MEDIA event, when receive CHANNEL_PROGRESS_MEDIA event perform uuid_record. use commands in fs_cli: originate {return_ring_ready=true}sofia/gateway/pressure/787675 &park()

and I use another freeswitch as the callee; callee freeswitch has diaplan:

<extension name="testesl765"> <condition field="destination_number" expression="^787675$"> <action application="sleep" data="5000"/> <action application="pre_answer"/> <action application="sleep" data="1"/> <action application="hangup"/> </condition> </extension>

406112767 avatar Nov 29 '22 08:11 406112767

@greenbea

Compare log discovery, have a deadlock. the following logs: 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr_async.c:1275 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:1755 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Write lock ACQUIRED

exception case log: 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [NOTICE] switch_channel.c:1123 New Channel sofia/external/787675 [766fe459-d040-441b-91e5-07052eb23ad5] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] mod_sofia.c:5121 (sofia/external/787675) State Change CS_NEW -> CS_INIT 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr_originate.c:3063 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/787675) Running State Change CS_INIT (Cur 7 Tot 9) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:624 (sofia/external/787675) State INIT 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] mod_sofia.c:97 sofia/external/787675 SOFIA INIT 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [INFO] sofia_glue.c:1651 sofia/external/787675 sending invite call-id: (null) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] sofia_glue.c:1654 sofia/external/787675 sending invite version: 1.10.7-release 64bit 766fe459-d040-441b-91e5-07052eb23ad5 Local SDP: 766fe459-d040-441b-91e5-07052eb23ad5 v=0 766fe459-d040-441b-91e5-07052eb23ad5 o=FreeSWITCH 1669846615 1669846616 IN IP4 192.168.31.232 766fe459-d040-441b-91e5-07052eb23ad5 s=FreeSWITCH 766fe459-d040-441b-91e5-07052eb23ad5 c=IN IP4 192.168.31.232 766fe459-d040-441b-91e5-07052eb23ad5 t=0 0 766fe459-d040-441b-91e5-07052eb23ad5 m=audio 26974 RTP/AVP 8 0 101 766fe459-d040-441b-91e5-07052eb23ad5 a=rtpmap:8 PCMA/8000 766fe459-d040-441b-91e5-07052eb23ad5 a=rtpmap:0 PCMU/8000 766fe459-d040-441b-91e5-07052eb23ad5 a=rtpmap:101 telephone-event/8000 766fe459-d040-441b-91e5-07052eb23ad5 a=fmtp:101 0-15 766fe459-d040-441b-91e5-07052eb23ad5 a=rtcp:26975 IN IP4 192.168.31.232 766fe459-d040-441b-91e5-07052eb23ad5 a=ptime:20 766fe459-d040-441b-91e5-07052eb23ad5 a=sendrecv 766fe459-d040-441b-91e5-07052eb23ad5 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:40 sofia/external/787675 Standard INIT 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:48 (sofia/external/787675) State Change CS_INIT -> CS_ROUTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:624 (sofia/external/787675) State INIT going to sleep 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/787675) Running State Change CS_ROUTING (Cur 7 Tot 9) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:640 (sofia/external/787675) State ROUTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] mod_sofia.c:158 sofia/external/787675 SOFIA ROUTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_ivr_originate.c:67 (sofia/external/787675) State Change CS_ROUTING -> CS_CONSUME_MEDIA 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:640 (sofia/external/787675) State ROUTING going to sleep 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/787675) Running State Change CS_CONSUME_MEDIA (Cur 7 Tot 9) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:659 (sofia/external/787675) State CONSUME_MEDIA 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] switch_core_state_machine.c:659 (sofia/external/787675) State CONSUME_MEDIA going to sleep 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] sofia.c:2636 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [ERR] sofia.c:2638 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:29.994188 90.53% [DEBUG] sofia.c:7499 Channel sofia/external/787675 entering state [calling][0] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2636 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2638 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2636 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2638 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] sofia.c:7499 Channel sofia/external/787675 entering state [proceeding][183] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] sofia.c:7509 Remote SDP: 766fe459-d040-441b-91e5-07052eb23ad5 v=0 766fe459-d040-441b-91e5-07052eb23ad5 o=FreeSWITCH 1669854793 1669854794 IN IP4 172.17.0.2 766fe459-d040-441b-91e5-07052eb23ad5 s=FreeSWITCH 766fe459-d040-441b-91e5-07052eb23ad5 c=IN IP4 172.17.0.2 766fe459-d040-441b-91e5-07052eb23ad5 t=0 0 766fe459-d040-441b-91e5-07052eb23ad5 m=audio 18802 RTP/AVP 8 101 766fe459-d040-441b-91e5-07052eb23ad5 a=rtpmap:8 PCMA/8000 766fe459-d040-441b-91e5-07052eb23ad5 a=rtpmap:101 telephone-event/8000 766fe459-d040-441b-91e5-07052eb23ad5 a=fmtp:101 0-15 766fe459-d040-441b-91e5-07052eb23ad5 a=ptime:20 766fe459-d040-441b-91e5-07052eb23ad5 a=rtcp:18803 IN IP4 172.17.0.2 766fe459-d040-441b-91e5-07052eb23ad5 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [NOTICE] sofia.c:7512 Pre-Answer sofia/external/787675! 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_channel.c:3622 (sofia/external/787675) Callstate Change DOWN -> EARLY 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:3870 Set Codec sofia/external/787675 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_codec.c:111 sofia/external/787675 Original read codec set to PCMA:8 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:5973 sofia/external/787675 Set 2833 dtmf send payload to 101 recv payload to 101 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:8776 AUDIO RTP [sofia/external/787675] 192.168.31.232 port 26974 -> 172.17.0.2 port 18802 codec: 8 ms: 20 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:8996 Activating RTCP PORT 18803 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_rtp.c:5017 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 18803 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_rtp.c:2776 Setting RTCP remote addr to 172.17.0.2:18803 2 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:9088 sofia/external/787675 Set 2833 dtmf send payload to 101 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:9095 sofia/external/787675 Set 2833 dtmf receive payload to 101 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_media.c:9118 sofia/external/787675 Set rtp dtmf delay to 40 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/external/787675] Peer UUID: 766fe459-d040-441b-91e5-07052eb23ad5 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_ivr.c:2289 (sofia/external/787675) State Change CS_CONSUME_MEDIA -> CS_ROUTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [NOTICE] switch_ivr.c:2296 Transfer sofia/external/787675 to XML[899987788@default] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] mod_commands.c:5178 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_state_machine.c:581 (sofia/external/787675) Running State Change CS_ROUTING (Cur 3 Tot 10) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_state_machine.c:640 (sofia/external/787675) State ROUTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] mod_sofia.c:158 sofia/external/787675 SOFIA ROUTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_state_machine.c:230 sofia/external/787675 Standard ROUTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [INFO] mod_dialplan_xml.c:639 Processing <0000000000>->899987788 in context default 766fe459-d040-441b-91e5-07052eb23ad5 Dialplan: sofia/external/787675 parsing [default->testesl] continue=false 766fe459-d040-441b-91e5-07052eb23ad5 Dialplan: sofia/external/787675 Regex (FAIL) [testesl] destination_number(899987788) =~ /^89998$/ break=on-false 766fe459-d040-441b-91e5-07052eb23ad5 Dialplan: sofia/external/787675 parsing [default->latest] continue=false 766fe459-d040-441b-91e5-07052eb23ad5 Dialplan: sofia/external/787675 Regex (PASS) [latest] destination_number(899987788) =~ /^899987788$/ break=on-false 766fe459-d040-441b-91e5-07052eb23ad5 Dialplan: sofia/external/787675 Action export(fire_asr_events=true) 766fe459-d040-441b-91e5-07052eb23ad5 Dialplan: sofia/external/787675 Action set(playback_terminators=none) 766fe459-d040-441b-91e5-07052eb23ad5 Dialplan: sofia/external/787675 Action park() 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [INFO] switch_channel.c:3270 sofia/external/787675 Flipping CID from "" <0000000000> to "787675" <787675> 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_state_machine.c:281 (sofia/external/787675) State Change CS_ROUTING -> CS_EXECUTE 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_state_machine.c:640 (sofia/external/787675) State ROUTING going to sleep 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_state_machine.c:581 (sofia/external/787675) Running State Change CS_EXECUTE (Cur 3 Tot 10) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_state_machine.c:647 (sofia/external/787675) State EXECUTE 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] mod_sofia.c:213 sofia/external/787675 SOFIA EXECUTE 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_core_state_machine.c:323 sofia/external/787675 Standard EXECUTE 766fe459-d040-441b-91e5-07052eb23ad5 EXECUTE [depth=0] sofia/external/787675 export(fire_asr_events=true) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [fire_asr_events]=[true] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 EXECUTE [depth=0] sofia/external/787675 set(playback_terminators=none) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [DEBUG] mod_dptools.c:1698 SET sofia/external/787675 [playback_terminators]=[none] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 EXECUTE [depth=0] sofia/external/787675 park() 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr.c:4023 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] mod_commands.c:4766 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] switch_ivr_async.c:1275 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2636 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2638 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2636 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2638 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2636 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] sofia.c:2638 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] mod_event_socket.c:2214 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.034223 90.27% [ERR] mod_event_socket.c:2220 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] sofia.c:6678 Remote Reason: 16 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] sofia.c:7499 Channel sofia/external/787675 entering state [terminated][480] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [NOTICE] sofia.c:8738 Hangup sofia/external/787675 [CS_EXECUTE] [NORMAL_CLEARING] 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:337 sofia/external/787675 skip receive message [VIDEO_SYNC] (channel is hungup already) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:338 sofia/external/787675 skip receive message [REQUEST_IMAGE_MEDIA] (channel is hungup already) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_ivr.c:632 sofia/external/787675 Command Execute [depth=1] playback(/startRing.wav) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_session.c:2760 sofia/external/787675 Channel is hungup and application playback does not have the zombie_exec flag. 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_session.c:2985 sofia/external/787675 skip receive message [PHONE_EVENT] (channel is hungup already) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:647 (sofia/external/787675) State EXECUTE going to sleep 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:581 (sofia/external/787675) Running State Change CS_HANGUP (Cur 3 Tot 10) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_ivr.c:679 sofia/external/787675 skip receive message [VIDEO_SYNC] (channel is hungup already) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:1009 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock FAIL 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:844 (sofia/external/787675) Callstate Change EARLY -> HANGUP 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:846 (sofia/external/787675) State HANGUP 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock FAIL 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] mod_sofia.c:468 Channel sofia/external/787675 hanging up, cause: NORMAL_CLEARING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:59 sofia/external/787675 Standard HANGUP, cause: NORMAL_CLEARING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:846 (sofia/external/787675) State HANGUP going to sleep 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:616 (sofia/external/787675) State Change CS_HANGUP -> CS_REPORTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:581 (sofia/external/787675) Running State Change CS_REPORTING (Cur 3 Tot 10) 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_ivr.c:4022 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock FAIL 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:932 (sofia/external/787675) State REPORTING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:168 sofia/external/787675 Standard REPORTING, cause: NORMAL_CLEARING 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:932 (sofia/external/787675) State REPORTING going to sleep 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_state_machine.c:607 (sofia/external/787675) State Change CS_REPORTING -> CS_DESTROY 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_session.c:1753 Session 9 (sofia/external/787675) Locked, Waiting on external entities 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:1755 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Write lock ACQUIRED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/external/787675 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] switch_core_session.c:865 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock FAIL 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.054186 90.27% [ERR] mod_commands.c:4823 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read/Write lock CLEARED 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.074185 90.27% [ERR] mod_event_socket.c:2214 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock FAIL 766fe459-d040-441b-91e5-07052eb23ad5 2022-12-01 13:46:35.074185 90.27% [ERR] switch_ivr.c:4399 766fe459-d040-441b-91e5-07052eb23ad5 sofia/external/787675 Read lock FAIL

406112767 avatar Dec 01 '22 07:12 406112767

I just had a similar issue on my server where my system crashed with abort. In the trace file I see

Thread 1 (Thread 0x7f98dc2b4700 (LWP 25848)):
#0  __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
#1  0x00007f9a330d4535 in __GI_abort () at abort.c:79
#2  0x00007f9a330d440f in __assert_fail_base (fmt=0x7f9a33236ee0 "%s%s%s:%u: %s%sAssertion `%s' failed.\n%n", assertion=0x7f9a33a2d222 "buffer->data != ((void *)0)", file=0x7f9a33a2d20e "src/switch_buffer.c", line=304, function=<optimized out>) at assert.c:92
#3  0x00007f9a330e2102 in __GI___assert_fail (assertion=assertion@entry=0x7f9a33a2d222 "buffer->data != ((void *)0)", file=file@entry=0x7f9a33a2d20e "src/switch_buffer.c", line=line@entry=304, function=function@entry=0x7f9a33a2d260 <__PRETTY_FUNCTION__.21464> "switch_buffer_write") at assert.c:101
#4  0x00007f9a336b612e in switch_buffer_write (buffer=0x7f99cdeb8770, data=data@entry=0x7f9932c84b98, datalen=5376) at src/switch_buffer.c:315
#5  0x00007f9a336d6d98 in switch_core_file_write (fh=0x7f99ce1fb848, data=data@entry=0x7f9932c84b98, len=len@entry=0x7f98dc2b3bb8) at src/switch_core_file.c:665
#6  0x00007f9a3377ea1e in recording_thread (thread=<optimized out>, obj=<optimized out>) at src/switch_ivr_async.c:1285
#7  0x00007f9a33a1d94c in dummy_worker (opaque=0x7f99cd982058) at threadproc/unix/thread.c:151
#8  0x00007f9a335f7fa3 in start_thread (arg=<optimized out>) at pthread_create.c:486
#9  0x00007f9a331ab4cf in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95

The last log before the crash I got

6d11fc7a-7bd6-11ed-8ed8-3b5d8b1522b7 2022-12-14 12:44:40.000657 [DEBUG] switch_core_session.c:1726 Session 3721783 (sofia/Internal2/9999@domain) Locked, Waiting on external entities

This is a freeswitch version 1.10.3, so I cannot say that it's a bug that exists also on 1.10.7

greenbea avatar Dec 14 '22 20:12 greenbea