Hi Everyone, I'm having one problem with my FusionPBX, I'll explain very quick;
Sometimes the PBX works good, but after a few hours of working, when I receive a inbound call from one provider I pick up the headset of my phone, the call is hanged by the system, I have tried with a lot of configuration with out success, and a temporary solution is to power Off and On the PBX, but the problem appears in a few minutes or hours.
P.D.: Excuse my English, it's not my native language. =)
FreeSWITCH 1528831999 1528832001 IN IP4 170.239.86.6
s=FreeSWITCH
c=IN IP4 170.239.86.6
t=0 0
m=audio 27382 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2018-06-12 23:09:42.964877 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/974993854@200.6.116.84:5060] has been answered
2018-06-12 23:09:42.964877 [DEBUG] switch_channel.c:3773 (sofia/internal/974993854@200.6.116.84:5060) Callstate Change EARLY -> ACTIVE
2018-06-12 23:09:42.964877 [DEBUG] sofia.c:7084 Channel sofia/internal/974993854@200.6.116.84:5060 entering state [completed][200]
2018-06-12 23:09:42.964877 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/301@201.187.96.190:5191]
2018-06-12 23:09:42.964877 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/301@201.187.96.190:5191]
2018-06-12 23:09:42.964877 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/301@201.187.96.190:5191) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2018-06-12 23:09:42.964877 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/301@201.187.96.190:5191) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 13)
2018-06-12 23:09:42.964877 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/301@201.187.96.190:5191) State EXCHANGE_MEDIA
2018-06-12 23:09:42.964877 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
2018-06-12 23:09:43.024839 [DEBUG] sofia.c:7084 Channel sofia/internal/974993854@200.6.116.84:5060 entering state [ready][200]
2018-06-12 23:09:43.084834 [NOTICE] sofia.c:1012 Hangup sofia/internal/974993854@200.6.116.84:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2018-06-12 23:09:43.084834 [DEBUG] mod_hash.c:297 Usage for 170.239.86.6_301 is now 0
2018-06-12 23:09:43.084834 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/974993854@200.6.116.84:5060]
2018-06-12 23:09:43.104818 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/301@201.187.96.190:5191]
2018-06-12 23:09:43.104818 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/internal/301@201.187.96.190:5191 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/301@201.187.96.190:5191) State EXCHANGE_MEDIA going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/301@201.187.96.190:5191) Running State Change CS_HANGUP (Cur 2 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/301@201.187.96.190:5191) Callstate Change ACTIVE -> HANGUP
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/301@201.187.96.190:5191) State HANGUP
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:432 sofia/internal/301@201.187.96.190:5191 Overriding SIP cause 480 with 200 from the other leg
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:438 Channel sofia/internal/301@201.187.96.190:5191 hanging up, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/301@201.187.96.190:5191
2018-06-12 23:09:43.104818 [DEBUG] switch_ivr_bridge.c:1712 sofia/internal/301@201.187.96.190:5191 skip receive message [UNBRIDGE] (channel is hungup already)
2018-06-12 23:09:43.104818 [DEBUG] switch_ivr_bridge.c:1715 sofia/internal/974993854@200.6.116.84:5060 skip receive message [UNBRIDGE] (channel is hungup already)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_session.c:2815 sofia/internal/974993854@200.6.116.84:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/974993854@200.6.116.84:5060) State EXECUTE going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/974993854@200.6.116.84:5060) Running State Change CS_HANGUP (Cur 2 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:60 sofia/internal/301@201.187.96.190:5191 Standard HANGUP, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/301@201.187.96.190:5191) State HANGUP going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/301@201.187.96.190:5191) State Change CS_HANGUP -> CS_REPORTING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/301@201.187.96.190:5191) Running State Change CS_REPORTING (Cur 2 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/974993854@200.6.116.84:5060) Callstate Change ACTIVE -> HANGUP
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/974993854@200.6.116.84:5060) State HANGUP
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:438 Channel sofia/internal/974993854@200.6.116.84:5060 hanging up, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/301@201.187.96.190:5191) State REPORTING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:174 sofia/internal/301@201.187.96.190:5191 Standard REPORTING, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/301@201.187.96.190:5191) State REPORTING going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/301@201.187.96.190:5191) State Change CS_REPORTING -> CS_DESTROY
2018-06-12 23:09:43.104818 [DEBUG] switch_core_session.c:1665 Session 13 (sofia/internal/301@201.187.96.190:5191) Locked, Waiting on external entities
2018-06-12 23:09:43.104818 [NOTICE] switch_core_session.c:1683 Session 13 (sofia/internal/301@201.187.96.190:5191) Ended
2018-06-12 23:09:43.104818 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/301@201.187.96.190:5191 [CS_DESTROY]
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/301@201.187.96.190:5191) Running State Change CS_DESTROY (Cur 1 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/301@201.187.96.190:5191) State DESTROY
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:343 sofia/internal/301@201.187.96.190:5191 SOFIA DESTROY
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:181 sofia/internal/301@201.187.96.190:5191 Standard DESTROY
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/301@201.187.96.190:5191) State DESTROY going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:60 sofia/internal/974993854@200.6.116.84:5060 Standard HANGUP, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/974993854@200.6.116.84:5060) State HANGUP going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(app.lua hangup):
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/974993854@200.6.116.84:5060) State Change CS_HANGUP -> CS_REPORTING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/974993854@200.6.116.84:5060) Running State Change CS_REPORTING (Cur 1 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/974993854@200.6.116.84:5060) State REPORTING
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:174 sofia/internal/974993854@200.6.116.84:5060 Standard REPORTING, cause: NORMAL_CLEARING
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/974993854@200.6.116.84:5060) State REPORTING going to sleep
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/974993854@200.6.116.84:5060) State Change CS_REPORTING -> CS_DESTROY
2018-06-12 23:09:43.164816 [DEBUG] switch_core_session.c:1665 Session 12 (sofia/internal/974993854@200.6.116.84:5060) Locked, Waiting on external entities
2018-06-12 23:09:43.164816 [NOTICE] switch_core_session.c:1683 Session 12 (sofia/internal/974993854@200.6.116.84:5060) Ended
2018-06-12 23:09:43.164816 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/974993854@200.6.116.84:5060 [CS_DESTROY]
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/974993854@200.6.116.84:5060) Running State Change CS_DESTROY (Cur 0 Tot 13)
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/974993854@200.6.116.84:5060) State DESTROY
2018-06-12 23:09:43.164816 [DEBUG] mod_sofia.c:343 sofia/internal/974993854@200.6.116.84:5060 SOFIA DESTROY
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:181 sofia/internal/974993854@200.6.116.84:5060 Standard DESTROY
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/974993854@200.6.116.84:5060) State DESTROY going to sleep
Sometimes the PBX works good, but after a few hours of working, when I receive a inbound call from one provider I pick up the headset of my phone, the call is hanged by the system, I have tried with a lot of configuration with out success, and a temporary solution is to power Off and On the PBX, but the problem appears in a few minutes or hours.
P.D.: Excuse my English, it's not my native language. =)
FreeSWITCH 1528831999 1528832001 IN IP4 170.239.86.6
s=FreeSWITCH
c=IN IP4 170.239.86.6
t=0 0
m=audio 27382 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2018-06-12 23:09:42.964877 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/974993854@200.6.116.84:5060] has been answered
2018-06-12 23:09:42.964877 [DEBUG] switch_channel.c:3773 (sofia/internal/974993854@200.6.116.84:5060) Callstate Change EARLY -> ACTIVE
2018-06-12 23:09:42.964877 [DEBUG] sofia.c:7084 Channel sofia/internal/974993854@200.6.116.84:5060 entering state [completed][200]
2018-06-12 23:09:42.964877 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/301@201.187.96.190:5191]
2018-06-12 23:09:42.964877 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/301@201.187.96.190:5191]
2018-06-12 23:09:42.964877 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/301@201.187.96.190:5191) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2018-06-12 23:09:42.964877 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/301@201.187.96.190:5191) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 13)
2018-06-12 23:09:42.964877 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/301@201.187.96.190:5191) State EXCHANGE_MEDIA
2018-06-12 23:09:42.964877 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
2018-06-12 23:09:43.024839 [DEBUG] sofia.c:7084 Channel sofia/internal/974993854@200.6.116.84:5060 entering state [ready][200]
2018-06-12 23:09:43.084834 [NOTICE] sofia.c:1012 Hangup sofia/internal/974993854@200.6.116.84:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2018-06-12 23:09:43.084834 [DEBUG] mod_hash.c:297 Usage for 170.239.86.6_301 is now 0
2018-06-12 23:09:43.084834 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/974993854@200.6.116.84:5060]
2018-06-12 23:09:43.104818 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/301@201.187.96.190:5191]
2018-06-12 23:09:43.104818 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/internal/301@201.187.96.190:5191 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/301@201.187.96.190:5191) State EXCHANGE_MEDIA going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/301@201.187.96.190:5191) Running State Change CS_HANGUP (Cur 2 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/301@201.187.96.190:5191) Callstate Change ACTIVE -> HANGUP
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/301@201.187.96.190:5191) State HANGUP
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:432 sofia/internal/301@201.187.96.190:5191 Overriding SIP cause 480 with 200 from the other leg
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:438 Channel sofia/internal/301@201.187.96.190:5191 hanging up, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/301@201.187.96.190:5191
2018-06-12 23:09:43.104818 [DEBUG] switch_ivr_bridge.c:1712 sofia/internal/301@201.187.96.190:5191 skip receive message [UNBRIDGE] (channel is hungup already)
2018-06-12 23:09:43.104818 [DEBUG] switch_ivr_bridge.c:1715 sofia/internal/974993854@200.6.116.84:5060 skip receive message [UNBRIDGE] (channel is hungup already)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_session.c:2815 sofia/internal/974993854@200.6.116.84:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/974993854@200.6.116.84:5060) State EXECUTE going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/974993854@200.6.116.84:5060) Running State Change CS_HANGUP (Cur 2 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:60 sofia/internal/301@201.187.96.190:5191 Standard HANGUP, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/301@201.187.96.190:5191) State HANGUP going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/301@201.187.96.190:5191) State Change CS_HANGUP -> CS_REPORTING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/301@201.187.96.190:5191) Running State Change CS_REPORTING (Cur 2 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/974993854@200.6.116.84:5060) Callstate Change ACTIVE -> HANGUP
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/974993854@200.6.116.84:5060) State HANGUP
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:438 Channel sofia/internal/974993854@200.6.116.84:5060 hanging up, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/301@201.187.96.190:5191) State REPORTING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:174 sofia/internal/301@201.187.96.190:5191 Standard REPORTING, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/301@201.187.96.190:5191) State REPORTING going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/301@201.187.96.190:5191) State Change CS_REPORTING -> CS_DESTROY
2018-06-12 23:09:43.104818 [DEBUG] switch_core_session.c:1665 Session 13 (sofia/internal/301@201.187.96.190:5191) Locked, Waiting on external entities
2018-06-12 23:09:43.104818 [NOTICE] switch_core_session.c:1683 Session 13 (sofia/internal/301@201.187.96.190:5191) Ended
2018-06-12 23:09:43.104818 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/301@201.187.96.190:5191 [CS_DESTROY]
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/301@201.187.96.190:5191) Running State Change CS_DESTROY (Cur 1 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/301@201.187.96.190:5191) State DESTROY
2018-06-12 23:09:43.104818 [DEBUG] mod_sofia.c:343 sofia/internal/301@201.187.96.190:5191 SOFIA DESTROY
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:181 sofia/internal/301@201.187.96.190:5191 Standard DESTROY
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/301@201.187.96.190:5191) State DESTROY going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:60 sofia/internal/974993854@200.6.116.84:5060 Standard HANGUP, cause: NORMAL_CLEARING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/974993854@200.6.116.84:5060) State HANGUP going to sleep
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(app.lua hangup):
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/974993854@200.6.116.84:5060) State Change CS_HANGUP -> CS_REPORTING
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/974993854@200.6.116.84:5060) Running State Change CS_REPORTING (Cur 1 Tot 13)
2018-06-12 23:09:43.104818 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/974993854@200.6.116.84:5060) State REPORTING
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:174 sofia/internal/974993854@200.6.116.84:5060 Standard REPORTING, cause: NORMAL_CLEARING
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/974993854@200.6.116.84:5060) State REPORTING going to sleep
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/974993854@200.6.116.84:5060) State Change CS_REPORTING -> CS_DESTROY
2018-06-12 23:09:43.164816 [DEBUG] switch_core_session.c:1665 Session 12 (sofia/internal/974993854@200.6.116.84:5060) Locked, Waiting on external entities
2018-06-12 23:09:43.164816 [NOTICE] switch_core_session.c:1683 Session 12 (sofia/internal/974993854@200.6.116.84:5060) Ended
2018-06-12 23:09:43.164816 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/974993854@200.6.116.84:5060 [CS_DESTROY]
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/974993854@200.6.116.84:5060) Running State Change CS_DESTROY (Cur 0 Tot 13)
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/974993854@200.6.116.84:5060) State DESTROY
2018-06-12 23:09:43.164816 [DEBUG] mod_sofia.c:343 sofia/internal/974993854@200.6.116.84:5060 SOFIA DESTROY
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:181 sofia/internal/974993854@200.6.116.84:5060 Standard DESTROY
2018-06-12 23:09:43.164816 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/974993854@200.6.116.84:5060) State DESTROY going to sleep