Good Day, list.
I am new to fusionpbx and it was working perfectly for last few weeks. And yesterday something did change and know i see every call hitting public context and failing.
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_NEW (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:9873 sofia/internal/1040@pbx.primax.com.au receiving invite from 115.186.137.147:3447 version: 1.6.19 -36-7a77e0b 64bit
2017-09-18 19:11:23.470764 [DEBUG] sofia.c:9989 IP 115.186.137.147 Approved by acl "domains[]". Access Granted.
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:11168 Setting NAT mode based on via received
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:7084 Channel sofia/internal/1040@pbx.primax.com.au entering state [received][100]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:7094 Remote SDP:
968288cf-bc6b-439f-b48c-a2831db3cf34 v=0
968288cf-bc6b-439f-b48c-a2831db3cf34 o=Z 0 0 IN IP4 192.168.0.110
968288cf-bc6b-439f-b48c-a2831db3cf34 s=Z
968288cf-bc6b-439f-b48c-a2831db3cf34 c=IN IP4 192.168.0.110
968288cf-bc6b-439f-b48c-a2831db3cf34 t=0 0
968288cf-bc6b-439f-b48c-a2831db3cf34 m=audio 32436 RTP/AVP 3 110 8 0 97 101
968288cf-bc6b-439f-b48c-a2831db3cf34 a=rtpmap:110 speex/8000
968288cf-bc6b-439f-b48c-a2831db3cf34 a=rtpmap:97 iLBC/8000
968288cf-bc6b-439f-b48c-a2831db3cf34 a=fmtp:97 mode=30
968288cf-bc6b-439f-b48c-a2831db3cf34 a=rtpmap:101 telephone-event/8000
968288cf-bc6b-439f-b48c-a2831db3cf34 a=fmtp:101 0-16
968288cf-bc6b-439f-b48c-a2831db3cf34
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1040@pbx.primax.com.au GSM/8000 20 ms 160 samples 13200 bits 1 channels
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_codec.c:111 sofia/internal/1040@pbx.primax.com.au Original read codec set to GSM:3
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4767 sofia/internal/1040@pbx.primax.com.au Set 2833 dtmf send payload to 101 recv payload to 101
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:7507 (sofia/internal/1040@pbx.primax.com.au) State Change CS_NEW -> CS_INIT
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1040@pbx.primax.com.au) State NEW
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_INIT (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1040@pbx.primax.com.au) State INIT
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] mod_sofia.c:90 sofia/internal/1040@pbx.primax.com.au SOFIA INIT
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1040@pbx.primax.com.au Standard INIT
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1040@pbx.primax.com.au) State Change CS_INIT -> CS_ROUTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1040@pbx.primax.com.au) State INIT going to sleep
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_ROUTING (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_channel.c:2249 (sofia/internal/1040@pbx.primax.com.au) Callstate Change DOWN -> RINGING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1040@pbx.primax.com.au) State ROUTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] mod_sofia.c:143 sofia/internal/1040@pbx.primax.com.au SOFIA ROUTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1040@pbx.primax.com.au Standard ROUTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [INFO] mod_dialplan_xml.c:637 Processing 1040 <1040>->2000 in context public
2017-09-18 19:11:23.470764 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f864016f140 Connected.
2017-09-18 19:11:23.490785 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f864016f140 released.
968288cf-bc6b-439f-b48c-a2831db3cf34 Dialplan: sofia/internal/1040@pbx.primax.com.au parsing [public->12123727200] continue=false
968288cf-bc6b-439f-b48c-a2831db3cf34 Dialplan: sofia/internal/1040@pbx.primax.com.au Regex (FAIL) [12123727200] destination_number(2000) =~ /^(12123727200)$/ break=on-false
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [INFO] switch_core_state_machine.c:311 No Route, Aborting
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [NOTICE] switch_core_state_machine.c:312 Hangup sofia/internal/1040@pbx.primax.com.au [CS_ROUTING] [NO_ROUTE_DESTINATION]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1040@pbx.primax.com.au) State ROUTING going to sleep
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_HANGUP (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1040@pbx.primax.com.au) Callstate Change RINGING -> HANGUP
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1040@pbx.primax.com.au) State HANGUP
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1040@pbx.primax.com.au hanging up, cause: NO_ROUTE_DESTINATION
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1040@pbx.primax.com.au Standard HANGUP, cause: NO_ROUTE_DESTINATION
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1040@pbx.primax.com.au) State HANGUP going to sleep
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1040@pbx.primax.com.au) State Change CS_HANGUP -> CS_REPORTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_REPORTING (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1040@pbx.primax.com.au) State REPORTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1040@pbx.primax.com.au Standard REPORTING, cause: NO_ROUTE_DESTINATION
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1040@pbx.primax.com.au) State REPORTING going to sleep
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1040@pbx.primax.com.au) State Change CS_REPORTING -> CS_DESTROY
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_session.c:1665 Session 391 (sofia/internal/1040@pbx.primax.com.au) Locked, Waiting on external entities
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [NOTICE] switch_core_session.c:1683 Session 391 (sofia/internal/1040@pbx.primax.com.au) Ended
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1040@pbx.primax.com.au [CS_DESTROY]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_DESTROY (Cur 0 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1040@pbx.primax.com.au) State DESTROY
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] mod_sofia.c:343 sofia/internal/1040@pbx.primax.com.au SOFIA DESTROY
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1040@pbx.primax.com.au Standard DESTROY
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1040@pbx.primax.com.au) State DESTROY going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1040@pbx.primax.com.au [48ca93ce-8106-4c6b-b19c-4102cea80b30]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_NEW (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:9873 sofia/internal/1040@pbx.primax.com.au receiving invite from 115.186.137.147:3447 version: 1.6.19 -36-7a77e0b 64bit
2017-09-18 19:15:26.430785 [DEBUG] sofia.c:9989 IP 115.186.137.147 Approved by acl "domains[]". Access Granted.
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:11168 Setting NAT mode based on via received
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:7084 Channel sofia/internal/1040@pbx.primax.com.au entering state [received][100]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:7094 Remote SDP:
48ca93ce-8106-4c6b-b19c-4102cea80b30 v=0
48ca93ce-8106-4c6b-b19c-4102cea80b30 o=Z 0 0 IN IP4 192.168.0.110
48ca93ce-8106-4c6b-b19c-4102cea80b30 s=Z
48ca93ce-8106-4c6b-b19c-4102cea80b30 c=IN IP4 192.168.0.110
48ca93ce-8106-4c6b-b19c-4102cea80b30 t=0 0
48ca93ce-8106-4c6b-b19c-4102cea80b30 m=audio 32436 RTP/AVP 3 110 8 0 97 101
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=rtpmap:110 speex/8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=rtpmap:97 iLBC/8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=fmtp:97 mode=30
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=rtpmap:101 telephone-event/8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=fmtp:101 0-16
48ca93ce-8106-4c6b-b19c-4102cea80b30
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1040@pbx.primax.com.au GSM/8000 20 ms 160 samples 13200 bits 1 channels
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_codec.c:111 sofia/internal/1040@pbx.primax.com.au Original read codec set to GSM:3
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4767 sofia/internal/1040@pbx.primax.com.au Set 2833 dtmf send payload to 101 recv payload to 101
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:7507 (sofia/internal/1040@pbx.primax.com.au) State Change CS_NEW -> CS_INIT
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1040@pbx.primax.com.au) State NEW
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_INIT (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1040@pbx.primax.com.au) State INIT
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] mod_sofia.c:90 sofia/internal/1040@pbx.primax.com.au SOFIA INIT
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1040@pbx.primax.com.au Standard INIT
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1040@pbx.primax.com.au) State Change CS_INIT -> CS_ROUTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1040@pbx.primax.com.au) State INIT going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_ROUTING (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_channel.c:2249 (sofia/internal/1040@pbx.primax.com.au) Callstate Change DOWN -> RINGING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1040@pbx.primax.com.au) State ROUTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] mod_sofia.c:143 sofia/internal/1040@pbx.primax.com.au SOFIA ROUTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1040@pbx.primax.com.au Standard ROUTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [INFO] mod_dialplan_xml.c:637 Processing 1040 <1040>->2000 in context public
48ca93ce-8106-4c6b-b19c-4102cea80b30 Dialplan: sofia/internal/1040@pbx.primax.com.au parsing [public->12123727200] continue=false
48ca93ce-8106-4c6b-b19c-4102cea80b30 Dialplan: sofia/internal/1040@pbx.primax.com.au Regex (FAIL) [12123727200] destination_number(2000) =~ /^(12123727200)$/ break=on-false
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [INFO] switch_core_state_machine.c:311 No Route, Aborting
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [NOTICE] switch_core_state_machine.c:312 Hangup sofia/internal/1040@pbx.primax.com.au [CS_ROUTING] [NO_ROUTE_DESTINATION]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1040@pbx.primax.com.au) State ROUTING going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_HANGUP (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1040@pbx.primax.com.au) Callstate Change RINGING -> HANGUP
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1040@pbx.primax.com.au) State HANGUP
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1040@pbx.primax.com.au hanging up, cause: NO_ROUTE_DESTINATION
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1040@pbx.primax.com.au Standard HANGUP, cause: NO_ROUTE_DESTINATION
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1040@pbx.primax.com.au) State HANGUP going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1040@pbx.primax.com.au) State Change CS_HANGUP -> CS_REPORTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_REPORTING (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1040@pbx.primax.com.au) State REPORTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1040@pbx.primax.com.au Standard REPORTING, cause: NO_ROUTE_DESTINATION
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1040@pbx.primax.com.au) State REPORTING going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1040@pbx.primax.com.au) State Change CS_REPORTING -> CS_DESTROY
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_session.c:1665 Session 392 (sofia/internal/1040@pbx.primax.com.au) Locked, Waiting on external entities
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [NOTICE] switch_core_session.c:1683 Session 392 (sofia/internal/1040@pbx.primax.com.au) Ended
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1040@pbx.primax.com.au [CS_DESTROY]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_DESTROY (Cur 0 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1040@pbx.primax.com.au) State DESTROY
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] mod_sofia.c:343 sofia/internal/1040@pbx.primax.com.au SOFIA DESTROY
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1040@pbx.primax.com.au Standard DESTROY
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1040@pbx.primax.com.au) State DESTROY going to sleep
2017-09-18 19:17:59.210814 [DEBUG] switch_rtp.c:1463 [ zrtp cache]: Storing ZRTP cache to ...
2017-09-18 19:17:59.210814 [DEBUG] switch_rtp.c:1324 Saving ZRTP cache: OK
2017-09-18 19:20:44.710786 [NOTICE] sofia_reg.c:448 Registering b57df185-16ec-42d8-b091-76fd50d3cea0
2017-09-18 19:20:44.970821 [DEBUG] sofia_reg.c:2435 Changing expire time to 2026 by request of proxy sip:sip.vocus.net
Any pointer on this.
Thanks.
I am new to fusionpbx and it was working perfectly for last few weeks. And yesterday something did change and know i see every call hitting public context and failing.
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_NEW (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:9873 sofia/internal/1040@pbx.primax.com.au receiving invite from 115.186.137.147:3447 version: 1.6.19 -36-7a77e0b 64bit
2017-09-18 19:11:23.470764 [DEBUG] sofia.c:9989 IP 115.186.137.147 Approved by acl "domains[]". Access Granted.
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:11168 Setting NAT mode based on via received
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:7084 Channel sofia/internal/1040@pbx.primax.com.au entering state [received][100]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:7094 Remote SDP:
968288cf-bc6b-439f-b48c-a2831db3cf34 v=0
968288cf-bc6b-439f-b48c-a2831db3cf34 o=Z 0 0 IN IP4 192.168.0.110
968288cf-bc6b-439f-b48c-a2831db3cf34 s=Z
968288cf-bc6b-439f-b48c-a2831db3cf34 c=IN IP4 192.168.0.110
968288cf-bc6b-439f-b48c-a2831db3cf34 t=0 0
968288cf-bc6b-439f-b48c-a2831db3cf34 m=audio 32436 RTP/AVP 3 110 8 0 97 101
968288cf-bc6b-439f-b48c-a2831db3cf34 a=rtpmap:110 speex/8000
968288cf-bc6b-439f-b48c-a2831db3cf34 a=rtpmap:97 iLBC/8000
968288cf-bc6b-439f-b48c-a2831db3cf34 a=fmtp:97 mode=30
968288cf-bc6b-439f-b48c-a2831db3cf34 a=rtpmap:101 telephone-event/8000
968288cf-bc6b-439f-b48c-a2831db3cf34 a=fmtp:101 0-16
968288cf-bc6b-439f-b48c-a2831db3cf34
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G722:9:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[GSM:3:8000:20:13200:1]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1040@pbx.primax.com.au GSM/8000 20 ms 160 samples 13200 bits 1 channels
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_codec.c:111 sofia/internal/1040@pbx.primax.com.au Original read codec set to GSM:3
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_media.c:4767 sofia/internal/1040@pbx.primax.com.au Set 2833 dtmf send payload to 101 recv payload to 101
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] sofia.c:7507 (sofia/internal/1040@pbx.primax.com.au) State Change CS_NEW -> CS_INIT
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1040@pbx.primax.com.au) State NEW
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_INIT (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1040@pbx.primax.com.au) State INIT
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] mod_sofia.c:90 sofia/internal/1040@pbx.primax.com.au SOFIA INIT
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1040@pbx.primax.com.au Standard INIT
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1040@pbx.primax.com.au) State Change CS_INIT -> CS_ROUTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1040@pbx.primax.com.au) State INIT going to sleep
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_ROUTING (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_channel.c:2249 (sofia/internal/1040@pbx.primax.com.au) Callstate Change DOWN -> RINGING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1040@pbx.primax.com.au) State ROUTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] mod_sofia.c:143 sofia/internal/1040@pbx.primax.com.au SOFIA ROUTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1040@pbx.primax.com.au Standard ROUTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.470764 [INFO] mod_dialplan_xml.c:637 Processing 1040 <1040>->2000 in context public
2017-09-18 19:11:23.470764 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f864016f140 Connected.
2017-09-18 19:11:23.490785 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f864016f140 released.
968288cf-bc6b-439f-b48c-a2831db3cf34 Dialplan: sofia/internal/1040@pbx.primax.com.au parsing [public->12123727200] continue=false
968288cf-bc6b-439f-b48c-a2831db3cf34 Dialplan: sofia/internal/1040@pbx.primax.com.au Regex (FAIL) [12123727200] destination_number(2000) =~ /^(12123727200)$/ break=on-false
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [INFO] switch_core_state_machine.c:311 No Route, Aborting
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [NOTICE] switch_core_state_machine.c:312 Hangup sofia/internal/1040@pbx.primax.com.au [CS_ROUTING] [NO_ROUTE_DESTINATION]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1040@pbx.primax.com.au) State ROUTING going to sleep
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_HANGUP (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1040@pbx.primax.com.au) Callstate Change RINGING -> HANGUP
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1040@pbx.primax.com.au) State HANGUP
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1040@pbx.primax.com.au hanging up, cause: NO_ROUTE_DESTINATION
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1040@pbx.primax.com.au Standard HANGUP, cause: NO_ROUTE_DESTINATION
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1040@pbx.primax.com.au) State HANGUP going to sleep
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1040@pbx.primax.com.au) State Change CS_HANGUP -> CS_REPORTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_REPORTING (Cur 1 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.490785 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1040@pbx.primax.com.au) State REPORTING
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1040@pbx.primax.com.au Standard REPORTING, cause: NO_ROUTE_DESTINATION
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1040@pbx.primax.com.au) State REPORTING going to sleep
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1040@pbx.primax.com.au) State Change CS_REPORTING -> CS_DESTROY
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_session.c:1665 Session 391 (sofia/internal/1040@pbx.primax.com.au) Locked, Waiting on external entities
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [NOTICE] switch_core_session.c:1683 Session 391 (sofia/internal/1040@pbx.primax.com.au) Ended
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1040@pbx.primax.com.au [CS_DESTROY]
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_DESTROY (Cur 0 Tot 391)
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1040@pbx.primax.com.au) State DESTROY
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] mod_sofia.c:343 sofia/internal/1040@pbx.primax.com.au SOFIA DESTROY
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1040@pbx.primax.com.au Standard DESTROY
968288cf-bc6b-439f-b48c-a2831db3cf34 2017-09-18 19:11:23.550758 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1040@pbx.primax.com.au) State DESTROY going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1040@pbx.primax.com.au [48ca93ce-8106-4c6b-b19c-4102cea80b30]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_NEW (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:9873 sofia/internal/1040@pbx.primax.com.au receiving invite from 115.186.137.147:3447 version: 1.6.19 -36-7a77e0b 64bit
2017-09-18 19:15:26.430785 [DEBUG] sofia.c:9989 IP 115.186.137.147 Approved by acl "domains[]". Access Granted.
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:11168 Setting NAT mode based on via received
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:7084 Channel sofia/internal/1040@pbx.primax.com.au entering state [received][100]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:7094 Remote SDP:
48ca93ce-8106-4c6b-b19c-4102cea80b30 v=0
48ca93ce-8106-4c6b-b19c-4102cea80b30 o=Z 0 0 IN IP4 192.168.0.110
48ca93ce-8106-4c6b-b19c-4102cea80b30 s=Z
48ca93ce-8106-4c6b-b19c-4102cea80b30 c=IN IP4 192.168.0.110
48ca93ce-8106-4c6b-b19c-4102cea80b30 t=0 0
48ca93ce-8106-4c6b-b19c-4102cea80b30 m=audio 32436 RTP/AVP 3 110 8 0 97 101
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=rtpmap:110 speex/8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=rtpmap:97 iLBC/8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=fmtp:97 mode=30
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=rtpmap:101 telephone-event/8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 a=fmtp:101 0-16
48ca93ce-8106-4c6b-b19c-4102cea80b30
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G722:9:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:30:0:1]/[GSM:3:8000:20:13200:1]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1040@pbx.primax.com.au GSM/8000 20 ms 160 samples 13200 bits 1 channels
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_codec.c:111 sofia/internal/1040@pbx.primax.com.au Original read codec set to GSM:3
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_media.c:4767 sofia/internal/1040@pbx.primax.com.au Set 2833 dtmf send payload to 101 recv payload to 101
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] sofia.c:7507 (sofia/internal/1040@pbx.primax.com.au) State Change CS_NEW -> CS_INIT
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1040@pbx.primax.com.au) State NEW
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_INIT (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1040@pbx.primax.com.au) State INIT
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] mod_sofia.c:90 sofia/internal/1040@pbx.primax.com.au SOFIA INIT
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1040@pbx.primax.com.au Standard INIT
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1040@pbx.primax.com.au) State Change CS_INIT -> CS_ROUTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1040@pbx.primax.com.au) State INIT going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_ROUTING (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_channel.c:2249 (sofia/internal/1040@pbx.primax.com.au) Callstate Change DOWN -> RINGING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1040@pbx.primax.com.au) State ROUTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] mod_sofia.c:143 sofia/internal/1040@pbx.primax.com.au SOFIA ROUTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1040@pbx.primax.com.au Standard ROUTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [INFO] mod_dialplan_xml.c:637 Processing 1040 <1040>->2000 in context public
48ca93ce-8106-4c6b-b19c-4102cea80b30 Dialplan: sofia/internal/1040@pbx.primax.com.au parsing [public->12123727200] continue=false
48ca93ce-8106-4c6b-b19c-4102cea80b30 Dialplan: sofia/internal/1040@pbx.primax.com.au Regex (FAIL) [12123727200] destination_number(2000) =~ /^(12123727200)$/ break=on-false
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [INFO] switch_core_state_machine.c:311 No Route, Aborting
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [NOTICE] switch_core_state_machine.c:312 Hangup sofia/internal/1040@pbx.primax.com.au [CS_ROUTING] [NO_ROUTE_DESTINATION]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1040@pbx.primax.com.au) State ROUTING going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_HANGUP (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1040@pbx.primax.com.au) Callstate Change RINGING -> HANGUP
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1040@pbx.primax.com.au) State HANGUP
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1040@pbx.primax.com.au hanging up, cause: NO_ROUTE_DESTINATION
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1040@pbx.primax.com.au Standard HANGUP, cause: NO_ROUTE_DESTINATION
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1040@pbx.primax.com.au) State HANGUP going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1040@pbx.primax.com.au) State Change CS_HANGUP -> CS_REPORTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_REPORTING (Cur 1 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.430785 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1040@pbx.primax.com.au) State REPORTING
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1040@pbx.primax.com.au Standard REPORTING, cause: NO_ROUTE_DESTINATION
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1040@pbx.primax.com.au) State REPORTING going to sleep
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1040@pbx.primax.com.au) State Change CS_REPORTING -> CS_DESTROY
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_session.c:1665 Session 392 (sofia/internal/1040@pbx.primax.com.au) Locked, Waiting on external entities
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [NOTICE] switch_core_session.c:1683 Session 392 (sofia/internal/1040@pbx.primax.com.au) Ended
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1040@pbx.primax.com.au [CS_DESTROY]
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1040@pbx.primax.com.au) Running State Change CS_DESTROY (Cur 0 Tot 392)
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1040@pbx.primax.com.au) State DESTROY
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] mod_sofia.c:343 sofia/internal/1040@pbx.primax.com.au SOFIA DESTROY
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1040@pbx.primax.com.au Standard DESTROY
48ca93ce-8106-4c6b-b19c-4102cea80b30 2017-09-18 19:15:26.510762 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1040@pbx.primax.com.au) State DESTROY going to sleep
2017-09-18 19:17:59.210814 [DEBUG] switch_rtp.c:1463 [ zrtp cache]: Storing ZRTP cache to ...
2017-09-18 19:17:59.210814 [DEBUG] switch_rtp.c:1324 Saving ZRTP cache: OK
2017-09-18 19:20:44.710786 [NOTICE] sofia_reg.c:448 Registering b57df185-16ec-42d8-b091-76fd50d3cea0
2017-09-18 19:20:44.970821 [DEBUG] sofia_reg.c:2435 Changing expire time to 2026 by request of proxy sip:sip.vocus.net
Any pointer on this.
Thanks.