Outbound Calls Not working on new Installation: Warning NEWBY!

jnthodge · 2020-02-03 21:01:13 UTC

Using the SNG7-PBX-64bit-1910, I am trying to get outbound routes working with my new Clearfly SIP Trunk. I’ve set the outbound CID to the phone number they provided me.

Inbound calls are succeeding. Outbound calls are getting a message “the number you have dialed has not been recognized”.

I am a 25-year technical person (software developer), but brand new to PBX’s.

I’ve created the trunk configuration, setup outbound routes with pretty much every conceivable dial pattern.

What am I doing wrong?

I’ve created users, registered the phones (Yealink), internal extension-to-extension calling is working.
Here is the log for several outbound call attempts:

[2020-02-03 02:05:48] VERBOSE[18012] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [3090241964@from-sip-external:1] NoOp(“PJSIP/anonymous-000001e4”, “Received incoming SIP connection from unknown peer to 3090241964”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [3090241964@from-sip-external:2] Set(“PJSIP/anonymous-000001e4”, “DID=3090241964”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [3090241964@from-sip-external:3] Goto(“PJSIP/anonymous-000001e4”, “s,1”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-000001e4”, “1?setlanguage:checkanon”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-000001e4”, “CHANNEL(language)=en”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-000001e4”, “1?noanonymous”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx_builtins.c: Goto (from-sip-external,s,5)
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:5] Set(“PJSIP/anonymous-000001e4”, “TIMEOUT(absolute)=15”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] func_timeout.c: Channel will hangup at 2020-02-03 02:06:03.055 UTC.
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:6] Set(“PJSIP/anonymous-000001e4”, “receveip=pjsip,remote_addr”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:7] Log(“PJSIP/anonymous-000001e4”, "WARNING,“Rejecting unknown SIP connection from 103.244.235.207:5650"”) in new stack
[2020-02-03 02:05:48] WARNING[5869][C-000001b7] Ext. s: “Rejecting unknown SIP connection from 103.244.235.207:5650”
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:8] Answer(“PJSIP/anonymous-000001e4”, “”) in new stack
[2020-02-03 02:05:48] VERBOSE[21191] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [301208442@from-sip-external:1] NoOp(“PJSIP/anonymous-000001e5”, “Received incoming SIP connection from unknown peer to 301208442”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [301208442@from-sip-external:2] Set(“PJSIP/anonymous-000001e5”, “DID=301208442”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [301208442@from-sip-external:3] Goto(“PJSIP/anonymous-000001e5”, “s,1”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-000001e5”, “1?setlanguage:checkanon”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-000001e5”, “CHANNEL(language)=en”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-000001e5”, “1?noanonymous”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx_builtins.c: Goto (from-sip-external,s,5)
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:5] Set(“PJSIP/anonymous-000001e5”, “TIMEOUT(absolute)=15”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] func_timeout.c: Channel will hangup at 2020-02-03 02:06:03.075 UTC.
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:6] Set(“PJSIP/anonymous-000001e5”, “receveip=pjsip,remote_addr”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:7] Log(“PJSIP/anonymous-000001e5”, "WARNING,“Rejecting unknown SIP connection from 103.244.235.207:5651"”) in new stack
[2020-02-03 02:05:48] WARNING[5870][C-000001b8] Ext. s: “Rejecting unknown SIP connection from 103.244.235.207:5651”
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:8] Answer(“PJSIP/anonymous-000001e5”, “”) in new stack
[2020-02-03 02:05:48] VERBOSE[7617] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-03 02:05:48] VERBOSE[21191] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [0@from-sip-external:1] NoOp(“PJSIP/anonymous-000001e6”, “Received incoming SIP connection from unknown peer to 0”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [104@from-sip-external:1] NoOp(“PJSIP/anonymous-000001e7”, “Received incoming SIP connection from unknown peer to 104”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [0@from-sip-external:2] Set(“PJSIP/anonymous-000001e6”, “DID=s”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [104@from-sip-external:2] Set(“PJSIP/anonymous-000001e7”, “DID=104”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [104@from-sip-external:3] Goto(“PJSIP/anonymous-000001e7”, “s,1”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [0@from-sip-external:3] Goto(“PJSIP/anonymous-000001e6”, “s,1”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-000001e6”, “1?setlanguage:checkanon”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-000001e7”, “1?setlanguage:checkanon”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-000001e7”, “CHANNEL(language)=en”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-000001e7”, “1?noanonymous”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx_builtins.c: Goto (from-sip-external,s,5)
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:5] Set(“PJSIP/anonymous-000001e7”, “TIMEOUT(absolute)=15”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] func_timeout.c: Channel will hangup at 2020-02-03 02:06:03.300 UTC.
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:6] Set(“PJSIP/anonymous-000001e7”, “receveip=pjsip,remote_addr”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:7] Log(“PJSIP/anonymous-000001e7”, "WARNING,“Rejecting unknown SIP connection from 103.244.235.207:5650"”) in new stack
[2020-02-03 02:05:48] WARNING[5872][C-000001ba] Ext. s: “Rejecting unknown SIP connection from 103.244.235.207:5650”
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:8] Answer(“PJSIP/anonymous-000001e7”, “”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-000001e6”, “CHANNEL(language)=en”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-000001e6”, “1?noanonymous”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx_builtins.c: Goto (from-sip-external,s,5)
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:5] Set(“PJSIP/anonymous-000001e6”, “TIMEOUT(absolute)=15”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] func_timeout.c: Channel will hangup at 2020-02-03 02:06:03.302 UTC.
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:6] Set(“PJSIP/anonymous-000001e6”, “receveip=pjsip,remote_addr”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:7] Log(“PJSIP/anonymous-000001e6”, "WARNING,“Rejecting unknown SIP connection from 103.244.235.207:5651"”) in new stack
[2020-02-03 02:05:48] WARNING[5871][C-000001b9] Ext. s: “Rejecting unknown SIP connection from 103.244.235.207:5651”
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:8] Answer(“PJSIP/anonymous-000001e6”, “”) in new stack
[2020-02-03 02:05:48] VERBOSE[18012] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [1@from-sip-external:1] NoOp(“PJSIP/anonymous-000001e8”, “Received incoming SIP connection from unknown peer to 1”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [1@from-sip-external:2] Set(“PJSIP/anonymous-000001e8”, “DID=s”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [1@from-sip-external:3] Goto(“PJSIP/anonymous-000001e8”, “s,1”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-000001e8”, “1?setlanguage:checkanon”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-000001e8”, “CHANNEL(language)=en”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-000001e8”, “1?noanonymous”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx_builtins.c: Goto (from-sip-external,s,5)
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:5] Set(“PJSIP/anonymous-000001e8”, “TIMEOUT(absolute)=15”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] func_timeout.c: Channel will hangup at 2020-02-03 02:06:03.346 UTC.
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:6] Set(“PJSIP/anonymous-000001e8”, “receveip=pjsip,remote_addr”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:7] Log(“PJSIP/anonymous-000001e8”, "WARNING,“Rejecting unknown SIP connection from 103.244.235.207:5651"”) in new stack
[2020-02-03 02:05:48] WARNING[5873][C-000001bb] Ext. s: “Rejecting unknown SIP connection from 103.244.235.207:5651”
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:8] Answer(“PJSIP/anonymous-000001e8”, “”) in new stack
[2020-02-03 02:05:48] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:9] Wait(“PJSIP/anonymous-000001e4”, “2”) in new stack
[2020-02-03 02:05:48] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:9] Wait(“PJSIP/anonymous-000001e5”, “2”) in new stack
[2020-02-03 02:05:48] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:9] Wait(“PJSIP/anonymous-000001e7”, “2”) in new stack
[2020-02-03 02:05:48] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:9] Wait(“PJSIP/anonymous-000001e6”, “2”) in new stack
[2020-02-03 02:05:48] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:9] Wait(“PJSIP/anonymous-000001e8”, “2”) in new stack
[2020-02-03 02:05:50] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:10] Playback(“PJSIP/anonymous-000001e4”, “ss-noservice”) in new stack
[2020-02-03 02:05:50] VERBOSE[5869][C-000001b7] file.c: <PJSIP/anonymous-000001e4> Playing ‘ss-noservice.slin’ (language ‘en’)
[2020-02-03 02:05:50] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:10] Playback(“PJSIP/anonymous-000001e5”, “ss-noservice”) in new stack
[2020-02-03 02:05:50] VERBOSE[5870][C-000001b8] file.c: <PJSIP/anonymous-000001e5> Playing ‘ss-noservice.slin’ (language ‘en’)
[2020-02-03 02:05:50] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:10] Playback(“PJSIP/anonymous-000001e7”, “ss-noservice”) in new stack
[2020-02-03 02:05:50] VERBOSE[5872][C-000001ba] file.c: <PJSIP/anonymous-000001e7> Playing ‘ss-noservice.slin’ (language ‘en’)
[2020-02-03 02:05:50] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:10] Playback(“PJSIP/anonymous-000001e6”, “ss-noservice”) in new stack
[2020-02-03 02:05:50] VERBOSE[5871][C-000001b9] file.c: <PJSIP/anonymous-000001e6> Playing ‘ss-noservice.slin’ (language ‘en’)
[2020-02-03 02:05:50] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:10] Playback(“PJSIP/anonymous-000001e8”, “ss-noservice”) in new stack
[2020-02-03 02:05:50] VERBOSE[5873][C-000001bb] file.c: <PJSIP/anonymous-000001e8> Playing ‘ss-noservice.slin’ (language ‘en’)
[2020-02-03 02:05:55] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:11] PlayTones(“PJSIP/anonymous-000001e4”, “congestion”) in new stack
[2020-02-03 02:05:55] VERBOSE[5869][C-000001b7] pbx.c: Executing [s@from-sip-external:12] Congestion(“PJSIP/anonymous-000001e4”, “5”) in new stack
[2020-02-03 02:05:55] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:11] PlayTones(“PJSIP/anonymous-000001e5”, “congestion”) in new stack
[2020-02-03 02:05:55] VERBOSE[5870][C-000001b8] pbx.c: Executing [s@from-sip-external:12] Congestion(“PJSIP/anonymous-000001e5”, “5”) in new stack
[2020-02-03 02:05:55] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:11] PlayTones(“PJSIP/anonymous-000001e7”, “congestion”) in new stack
[2020-02-03 02:05:55] VERBOSE[5872][C-000001ba] pbx.c: Executing [s@from-sip-external:12] Congestion(“PJSIP/anonymous-000001e7”, “5”) in new stack
[2020-02-03 02:05:55] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:11] PlayTones(“PJSIP/anonymous-000001e6”, “congestion”) in new stack
[2020-02-03 02:05:55] VERBOSE[5871][C-000001b9] pbx.c: Executing [s@from-sip-external:12] Congestion(“PJSIP/anonymous-000001e6”, “5”) in new stack
[2020-02-03 02:05:55] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:11] PlayTones(“PJSIP/anonymous-000001e8”, “congestion”) in new stack
[2020-02-03 02:05:55] VERBOSE[5873][C-000001bb] pbx.c: Executing [s@from-sip-external:12] Congestion(“PJSIP/anonymous-000001e8”, “5”) in new stack
[2020-02-03 02:06:00] VERBOSE[5869][C-000001b7] pbx.c: Spawn extension (from-sip-external, s, 12) exited non-zero on ‘PJSIP/anonymous-000001e4’
[2020-02-03 02:06:00] VERBOSE[5869][C-000001b7] pbx.c: Executing [h@from-sip-external:1] Hangup(“PJSIP/anonymous-000001e4”, “”) in new stack
[2020-02-03 02:06:00] VERBOSE[5869][C-000001b7] pbx.c: Spawn extension (from-sip-external, h, 1) exited non-zero on ‘PJSIP/anonymous-000001e4’
[2020-02-03 02:06:00] VERBOSE[5870][C-000001b8] pbx.c: Spawn extension (from-sip-external, s, 12) exited non-zero on ‘PJSIP/anonymous-000001e5’
[2020-02-03 02:06:00] VERBOSE[5870][C-000001b8] pbx.c: Executing [h@from-sip-external:1] Hangup(“PJSIP/anonymous-000001e5”, “”) in new stack
[2020-02-03 02:06:00] VERBOSE[5870][C-000001b8] pbx.c: Spawn extension (from-sip-external, h, 1) exited non-zero on ‘PJSIP/anonymous-000001e5’
[2020-02-03 02:06:00] VERBOSE[5872][C-000001ba] pbx.c: Spawn extension (from-sip-external, s, 12) exited non-zero on ‘PJSIP/anonymous-000001e7’
[2020-02-03 02:06:00] VERBOSE[5872][C-000001ba] pbx.c: Executing [h@from-sip-external:1] Hangup(“PJSIP/anonymous-000001e7”, “”) in new stack
[2020-02-03 02:06:00] VERBOSE[5872][C-000001ba] pbx.c: Spawn extension (from-sip-external, h, 1) exited non-zero on ‘PJSIP/anonymous-000001e7’
[2020-02-03 02:06:00] VERBOSE[5871][C-000001b9] pbx.c: Spawn extension (from-sip-external, s, 12) exited non-zero on ‘PJSIP/anonymous-000001e6’
[2020-02-03 02:06:00] VERBOSE[5871][C-000001b9] pbx.c: Executing [h@from-sip-external:1] Hangup(“PJSIP/anonymous-000001e6”, “”) in new stack
[2020-02-03 02:06:00] VERBOSE[5871][C-000001b9] pbx.c: Spawn extension (from-sip-external, h, 1) exited non-zero on ‘PJSIP/anonymous-000001e6’
[2020-02-03 02:06:00] VERBOSE[5873][C-000001bb] pbx.c: Spawn extension (from-sip-external, s, 12) exited non-zero on ‘PJSIP/anonymous-000001e8’
[2020-02-03 02:06:00] VERBOSE[5873][C-000001bb] pbx.c: Executing [h@from-sip-external:1] Hangup(“PJSIP/anonymous-000001e8”, “”) in new stack
[2020-02-03 02:06:00] VERBOSE[5873][C-000001bb] pbx.c: Spawn extension (from-sip-external, h, 1) exited non-zero on ‘PJSIP/anonymous-000001e8’
[2020-02-03 02:06:08] VERBOSE[18376] res_pjsip/pjsip_configuration.c: Endpoint ClearflySIPTrunk is now Unreachable
[2020-02-03 02:06:08] VERBOSE[18376] res_pjsip/pjsip_options.c: Contact ClearflySIPTrunk/sip:3852382040@sc.cfly.co:5060 is now Unreachable. RTT: 0.000 msec
[2020-02-03 02:07:05] VERBOSE[18376] res_pjsip/pjsip_configuration.c: Endpoint ClearflySIPTrunk is now Reachable
[2020-02-03 02:07:05] VERBOSE[18376] res_pjsip/pjsip_options.c: Contact ClearflySIPTrunk/sip:3852382040@sc.cfly.co:5060 is now Reachable. RTT: 91.865 msec
[2020-02-03 02:09:24] VERBOSE[18376] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [601146586739261@from-sip-external:1] NoOp(“PJSIP/anonymous-000001e9”, “Received incoming SIP connection from unknown peer to 601146586739261”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [601146586739261@from-sip-external:2] Set(“PJSIP/anonymous-000001e9”, “DID=601146586739261”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [601146586739261@from-sip-external:3] Goto(“PJSIP/anonymous-000001e9”, “s,1”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-000001e9”, “1?setlanguage:checkanon”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-000001e9”, “CHANNEL(language)=en”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-000001e9”, “1?noanonymous”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx_builtins.c: Goto (from-sip-external,s,5)
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:5] Set(“PJSIP/anonymous-000001e9”, “TIMEOUT(absolute)=15”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] func_timeout.c: Channel will hangup at 2020-02-03 02:09:39.492 UTC.
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:6] Set(“PJSIP/anonymous-000001e9”, “receveip=pjsip,remote_addr”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:7] Log(“PJSIP/anonymous-000001e9”, "WARNING,“Rejecting unknown SIP connection from 103.91.65.203:56493"”) in new stack
[2020-02-03 02:09:24] WARNING[6151][C-000001bc] Ext. s: “Rejecting unknown SIP connection from 103.91.65.203:56493”
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:8] Answer(“PJSIP/anonymous-000001e9”, “”) in new stack
[2020-02-03 02:09:24] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:9] Wait(“PJSIP/anonymous-000001e9”, “2”) in new stack
[2020-02-03 02:09:26] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:10] Playback(“PJSIP/anonymous-000001e9”, “ss-noservice”) in new stack
[2020-02-03 02:09:26] VERBOSE[6151][C-000001bc] file.c: <PJSIP/anonymous-000001e9> Playing ‘ss-noservice.ulaw’ (language ‘en’)
[2020-02-03 02:09:31] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:11] PlayTones(“PJSIP/anonymous-000001e9”, “congestion”) in new stack
[2020-02-03 02:09:31] VERBOSE[6151][C-000001bc] pbx.c: Executing [s@from-sip-external:12] Congestion(“PJSIP/anonymous-000001e9”, “5”) in new stack
[2020-02-03 02:09:36] VERBOSE[6151][C-000001bc] pbx.c: Spawn extension (from-sip-external, s, 12) exited non-zero on ‘PJSIP/anonymous-000001e9’
[2020-02-03 02:09:36] VERBOSE[6151][C-000001bc] pbx.c: Executing [h@from-sip-external:1] Hangup(“PJSIP/anonymous-000001e9”, “”) in new stack
[2020-02-03 02:09:36] VERBOSE[6151][C-000001bc] pbx.c: Spawn extension (from-sip-external, h, 1) exited non-zero on ‘PJSIP/anonymous-000001e9’
[2020-02-03 02:10:52] WARNING[18376] res_pjsip_registrar.c: Endpoint ‘anonymous’ has no configured AORs
[2020-02-03 02:12:42] VERBOSE[7617] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [90046586739261@from-sip-external:1] NoOp(“PJSIP/anonymous-000001ea”, “Received incoming SIP connection from unknown peer to 90046586739261”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [90046586739261@from-sip-external:2] Set(“PJSIP/anonymous-000001ea”, “DID=90046586739261”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [90046586739261@from-sip-external:3] Goto(“PJSIP/anonymous-000001ea”, “s,1”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-000001ea”, “1?setlanguage:checkanon”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-000001ea”, “CHANNEL(language)=en”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-000001ea”, “1?noanonymous”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx_builtins.c: Goto (from-sip-external,s,5)
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:5] Set(“PJSIP/anonymous-000001ea”, “TIMEOUT(absolute)=15”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] func_timeout.c: Channel will hangup at 2020-02-03 02:12:57.072 UTC.
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:6] Set(“PJSIP/anonymous-000001ea”, “receveip=pjsip,remote_addr”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:7] Log(“PJSIP/anonymous-000001ea”, "WARNING,“Rejecting unknown SIP connection from 103.91.65.203:57924"”) in new stack
[2020-02-03 02:12:42] WARNING[6504][C-000001bd] Ext. s: “Rejecting unknown SIP connection from 103.91.65.203:57924”
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:8] Answer(“PJSIP/anonymous-000001ea”, “”) in new stack
[2020-02-03 02:12:42] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:9] Wait(“PJSIP/anonymous-000001ea”, “2”) in new stack
[2020-02-03 02:12:44] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:10] Playback(“PJSIP/anonymous-000001ea”, “ss-noservice”) in new stack
[2020-02-03 02:12:44] VERBOSE[6504][C-000001bd] file.c: <PJSIP/anonymous-000001ea> Playing ‘ss-noservice.ulaw’ (language ‘en’)
[2020-02-03 02:12:49] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:11] PlayTones(“PJSIP/anonymous-000001ea”, “congestion”) in new stack
[2020-02-03 02:12:49] VERBOSE[6504][C-000001bd] pbx.c: Executing [s@from-sip-external:12] Congestion(“PJSIP/anonymous-000001ea”, “5”) in new stack
[2020-02-03 02:12:54] VERBOSE[6504][C-000001bd] pbx.c: Spawn extension (from-sip-external, s, 12) exited non-zero on ‘PJSIP/anonymous-000001ea’
[2020-02-03 02:12:54] VERBOSE[6504][C-000001bd] pbx.c: Executing [h@from-sip-external:1] Hangup(“PJSIP/anonymous-000001ea”, “”) in new stack
[2020-02-03 02:12:54] VERBOSE[6504][C-000001bd] pbx.c: Spawn extension (from-sip-external, h, 1) exited non-zero on ‘PJSIP/anonymous-000001ea’
[2020-02-03 02:15:43] VERBOSE[21191] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [101146586739261@from-sip-external:1] NoOp(“PJSIP/anonymous-000001eb”, “Received incoming SIP connection from unknown peer to 101146586739261”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [101146586739261@from-sip-external:2] Set(“PJSIP/anonymous-000001eb”, “DID=101146586739261”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [101146586739261@from-sip-external:3] Goto(“PJSIP/anonymous-000001eb”, “s,1”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-000001eb”, “1?setlanguage:checkanon”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-000001eb”, “CHANNEL(language)=en”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-000001eb”, “1?noanonymous”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx_builtins.c: Goto (from-sip-external,s,5)
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:5] Set(“PJSIP/anonymous-000001eb”, “TIMEOUT(absolute)=15”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] func_timeout.c: Channel will hangup at 2020-02-03 02:15:58.422 UTC.
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:6] Set(“PJSIP/anonymous-000001eb”, “receveip=pjsip,remote_addr”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:7] Log(“PJSIP/anonymous-000001eb”, "WARNING,“Rejecting unknown SIP connection from 103.91.65.203:65121"”) in new stack
[2020-02-03 02:15:43] WARNING[6800][C-000001be] Ext. s: “Rejecting unknown SIP connection from 103.91.65.203:65121”
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:8] Answer(“PJSIP/anonymous-000001eb”, “”) in new stack
[2020-02-03 02:15:43] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:9] Wait(“PJSIP/anonymous-000001eb”, “2”) in new stack
[2020-02-03 02:15:45] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:10] Playback(“PJSIP/anonymous-000001eb”, “ss-noservice”) in new stack
[2020-02-03 02:15:45] VERBOSE[6800][C-000001be] file.c: <PJSIP/anonymous-000001eb> Playing ‘ss-noservice.ulaw’ (language ‘en’)
[2020-02-03 02:15:50] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:11] PlayTones(“PJSIP/anonymous-000001eb”, “congestion”) in new stack
[2020-02-03 02:15:50] VERBOSE[6800][C-000001be] pbx.c: Executing [s@from-sip-external:12] Congestion(“PJSIP/anonymous-000001eb”, “5”) in new stack
[2020-02-03 02:15:55] VERBOSE[6800][C-000001be] pbx.c: Spawn extension (from-sip-external, s, 12) exited non-zero on ‘PJSIP/anonymous-000001eb’
[2020-02-03 02:15:55] VERBOSE[6800][C-000001be] pbx.c: Executing [h@from-sip-external:1] Hangup(“PJSIP/anonymous-000001eb”, “”) in new stack
[2020-02-03 02:15:55] VERBOSE[6800][C-000001be] pbx.c: Spawn extension (from-sip-external, h, 1) exited non-zero on ‘PJSIP/anonymous-000001eb’

jnthodge · 2020-02-03 21:03:09 UTC

This piece might be relevant. It has reference to a CDR getting reset and then no CDR found:

[2020-02-02 19:34:52] VERBOSE[21191] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-02 19:34:52] VERBOSE[21191] netsock2.c: Using SIP RTP Audio TOS bits 184
[2020-02-02 19:34:52] VERBOSE[21191] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2020-02-02 19:34:52] VERBOSE[21191] netsock2.c: Using SIP RTP Audio CoS mark 5
[2020-02-02 19:34:52] VERBOSE[29610][C-00000143] pbx.c: Executing [8016285686@from-internal:1] ResetCDR(“PJSIP/101-0000015d”, “”) in new stack
[2020-02-02 19:34:52] VERBOSE[29610][C-00000143] pbx.c: Executing [8016285686@from-internal:2] NoCDR(“PJSIP/101-0000015d”, “”) in new stack
[2020-02-02 19:34:52] VERBOSE[29610][C-00000143] pbx.c: Executing [8016285686@from-internal:3] Progress(“PJSIP/101-0000015d”, “”) in new stack
[2020-02-02 19:34:52] VERBOSE[29610][C-00000143] pbx.c: Executing [8016285686@from-internal:4] Wait(“PJSIP/101-0000015d”, “1”) in new stack
[2020-02-02 19:34:53] VERBOSE[29610][C-00000143] pbx.c: Executing [8016285686@from-internal:5] Playback(“PJSIP/101-0000015d”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2020-02-02 19:34:53] VERBOSE[29610][C-00000143] file.c: <PJSIP/101-0000015d> Playing ‘silence/1.ulaw’ (language ‘en’)
[2020-02-02 19:34:54] VERBOSE[29610][C-00000143] file.c: <PJSIP/101-0000015d> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2020-02-02 19:34:56] VERBOSE[29610][C-00000143] file.c: <PJSIP/101-0000015d> Playing ‘check-number-dial-again.ulaw’ (language ‘en’)
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/101-0000015d”, “hangupcall”) in new stack
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/101-0000015d”, “1?theend”) in new stack
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/101-0000015d”, “0?Set(CDR(recordingfile)=)”) in new stack
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/101-0000015d”, " montior file= ") in new stack
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“PJSIP/101-0000015d”, “1?skipagi”) in new stack
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“PJSIP/101-0000015d”, “”) in new stack
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘PJSIP/101-0000015d’ in macro ‘hangupcall’
[2020-02-02 19:34:58] VERBOSE[29610][C-00000143] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/101-0000015d’
[2020-02-02 19:35:40] WARNING[7617] res_pjsip_registrar.c: Endpoint ‘anonymous’ has no configured AORs
[2020-02-02 19:36:05] VERBOSE[11880] asterisk.c: Remote UNIX connection
[2020-02-02 19:36:05] VERBOSE[29839] asterisk.c: Remote UNIX connection disconnected
[2020-02-02 19:36:05] VERBOSE[11880] asterisk.c: Remote UNIX connection
[2020-02-02 19:36:05] VERBOSE[29845] asterisk.c: Remote UNIX connection disconnected
[2020-02-02 19:36:05] VERBOSE[11880] asterisk.c: Remote UNIX connection
[2020-02-02 19:36:05] VERBOSE[29847] asterisk.c: Remote UNIX connection disconnected
[2020-02-02 19:36:05] VERBOSE[11880] asterisk.c: Remote UNIX connection
[2020-02-02 19:36:05] VERBOSE[29849] asterisk.c: Remote UNIX connection disconnected
[2020-02-02 19:36:06] VERBOSE[11880] asterisk.c: Remote UNIX connection

dicko · 2020-02-03 21:41:04 UTC

if you are in illinois, then your called number 3090241964 won’t match NXXNXXXXXX, did you mask the real number?

jnthodge · 2020-02-03 22:01:53 UTC

Hi dicko, you have an eagle eye! I turned my firewall off for a few minutes a couple of times. I wonder if those originated from a hacker or something. I don’t recognize that number. I am in Utah, actually.

Here is a more exemplary log of the call I was actually trying to make. It’s the number at Clearfly that I’m supposed to call to dial a number sequence that will finally finish the registration of the SIP install:

[2020-02-02 19:48:39] VERBOSE[18012] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-02 19:48:39] VERBOSE[18012] netsock2.c: Using SIP RTP Audio TOS bits 184
[2020-02-02 19:48:39] VERBOSE[18012] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2020-02-02 19:48:39] VERBOSE[18012] netsock2.c: Using SIP RTP Audio CoS mark 5
[2020-02-02 19:48:39] VERBOSE[32569][C-00000153] pbx.c: Executing [4062064848@from-internal:1] ResetCDR(“PJSIP/101-0000017a”, “”) in new stack
[2020-02-02 19:48:39] VERBOSE[32569][C-00000153] pbx.c: Executing [4062064848@from-internal:2] NoCDR(“PJSIP/101-0000017a”, “”) in new stack
[2020-02-02 19:48:39] VERBOSE[32569][C-00000153] pbx.c: Executing [4062064848@from-internal:3] Progress(“PJSIP/101-0000017a”, “”) in new stack
[2020-02-02 19:48:39] VERBOSE[32569][C-00000153] pbx.c: Executing [4062064848@from-internal:4] Wait(“PJSIP/101-0000017a”, “1”) in new stack
[2020-02-02 19:48:40] VERBOSE[32569][C-00000153] pbx.c: Executing [4062064848@from-internal:5] Playback(“PJSIP/101-0000017a”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2020-02-02 19:48:40] VERBOSE[32569][C-00000153] file.c: <PJSIP/101-0000017a> Playing ‘silence/1.ulaw’ (language ‘en’)
[2020-02-02 19:48:41] VERBOSE[32569][C-00000153] file.c: <PJSIP/101-0000017a> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] file.c: <PJSIP/101-0000017a> Playing ‘check-number-dial-again.ulaw’ (language ‘en’)
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/101-0000017a”, “hangupcall”) in new stack
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/101-0000017a”, “1?theend”) in new stack
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/101-0000017a”, “0?Set(CDR(recordingfile)=)”) in new stack
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/101-0000017a”, " montior file= ") in new stack
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“PJSIP/101-0000017a”, “1?skipagi”) in new stack
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“PJSIP/101-0000017a”, “”) in new stack
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘PJSIP/101-0000017a’ in macro ‘hangupcall’
[2020-02-02 19:48:43] VERBOSE[32569][C-00000153] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/101-0000017a’
[2020-02-02 19:48:53] VERBOSE[18012] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.10.36’
[2020-02-02 19:48:53] VERBOSE[18012] netsock2.c: Using SIP RTP Audio TOS bits 184
[2020-02-02 19:48:53] VERBOSE[18012] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2020-02-02 19:48:53] VERBOSE[18012] netsock2.c: Using SIP RTP Audio CoS mark 5
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [14062064848@from-internal:1] Macro(“PJSIP/101-0000017b”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/101-0000017b”, “TOUCH_MONITOR=1580672933.381”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/101-0000017b”, “AMPUSER=101”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/101-0000017b”, “0?report”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/101-0000017b”, “1?Set(REALCALLERIDNUM=101)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/101-0000017b”, “AMPUSER=101”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/101-0000017b”, “0?limit”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/101-0000017b”, “AMPUSERCIDNAME=Secretary2”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/101-0000017b”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/101-0000017b”, “0?report”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/101-0000017b”, “AMPUSERCID=101”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/101-0000017b”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/101-0000017b”, “CALLERID(all)=“Secretary2” <101>”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“PJSIP/101-0000017b”, “0?Set(CALLERID(all)=EXTERNAL)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“PJSIP/101-0000017b”, “0?limit”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/101-0000017b”, “1?Set(GROUP(concurrency_limit)=101)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:16] ExecIf(“PJSIP/101-0000017b”, “0?Set(CHANNEL(language)=)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:17] NoOp(“PJSIP/101-0000017b”, “Macro Depth is 1”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/101-0000017b”, “1?report2:macroerror”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (macro-user-callerid,s,19)
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/101-0000017b”, “1?continue”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (macro-user-callerid,s,38)
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/101-0000017b”, “CALLERID(number)=101”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:39] Set(“PJSIP/101-0000017b”, “CALLERID(name)=Secretary2”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:40] GotoIf(“PJSIP/101-0000017b”, “0?cnum”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/101-0000017b”, “CDR(cnam)=Secretary2”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/101-0000017b”, “CDR(cnum)=101”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-user-callerid:43] Set(“PJSIP/101-0000017b”, “CHANNEL(language)=en”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [14062064848@from-internal:2] Gosub(“PJSIP/101-0000017b”, “sub-record-check,s,1(out,14062064848,dontcare)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/101-0000017b”, “0?initialized”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/101-0000017b”, “__REC_STATUS=INITIALIZED”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/101-0000017b”, “NOW=1580672933”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/101-0000017b”, “__DAY=02”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/101-0000017b”, “__MONTH=02”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/101-0000017b”, “__YEAR=2020”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/101-0000017b”, “__TIMESTR=20200202-194853”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/101-0000017b”, “__FROMEXTEN=101”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/101-0000017b”, “__MON_FMT=wav”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/101-0000017b”, “Recordings initialized”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/101-0000017b”, “0?Set(ARG3=dontcare)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/101-0000017b”, “REC_POLICY_MODE_SAVE=”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/101-0000017b”, “0?Set(REC_STATUS=NO)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/101-0000017b”, “3?checkaction”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (sub-record-check,s,17)
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/101-0000017b”, “1?sub-record-check,out,1”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (sub-record-check,out,1)
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [out@sub-record-check:1] NoOp(“PJSIP/101-0000017b”, “Outbound Recording Check from 101 to 14062064848”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [out@sub-record-check:2] Set(“PJSIP/101-0000017b”, “RECMODE=dontcare”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [out@sub-record-check:3] ExecIf(“PJSIP/101-0000017b”, “1?Goto(routewins)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (sub-record-check,out,7)
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [out@sub-record-check:7] Gosub(“PJSIP/101-0000017b”, “recordcheck,1(dontcare,out,14062064848)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/101-0000017b”, “Starting recording check against dontcare”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/101-0000017b”, “dontcare”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/101-0000017b”, “”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [out@sub-record-check:8] Return(“PJSIP/101-0000017b”, “”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [14062064848@from-internal:3] ExecIf(“PJSIP/101-0000017b”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [14062064848@from-internal:4] Set(“PJSIP/101-0000017b”, “MOHCLASS=default”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [14062064848@from-internal:5] ExecIf(“PJSIP/101-0000017b”, “1?Set(TRUNKCIDOVERRIDE=3852382040)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [14062064848@from-internal:6] Set(“PJSIP/101-0000017b”, “_NODEST=”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [14062064848@from-internal:7] Macro(“PJSIP/101-0000017b”, “dialout-trunk,1,14062064848,off”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“PJSIP/101-0000017b”, “DIAL_TRUNK=1”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf(“PJSIP/101-0000017b”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf(“PJSIP/101-0000017b”, “0?sub-pincheck,s,1()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf(“PJSIP/101-0000017b”, “0?Set(CALLERID(num)=101)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf(“PJSIP/101-0000017b”, “0?disabletrunk,1”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“PJSIP/101-0000017b”, “DIAL_NUMBER=14062064848”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:7] Set(“PJSIP/101-0000017b”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“PJSIP/101-0000017b”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:9] Set(“PJSIP/101-0000017b”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf(“PJSIP/101-0000017b”, “0?nomax”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf(“PJSIP/101-0000017b”, “0?chanfull”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf(“PJSIP/101-0000017b”, “0?skipoutcid”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:13] Macro(“PJSIP/101-0000017b”, “outbound-callerid,1”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“PJSIP/101-0000017b”, “101”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“PJSIP/101-0000017b”, “”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“PJSIP/101-0000017b”, “off”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(“PJSIP/101-0000017b”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(“PJSIP/101-0000017b”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf(“PJSIP/101-0000017b”, “0?Set(REALCALLERIDNUM=101)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf(“PJSIP/101-0000017b”, “0?Set(AMPUSER=101)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf(“PJSIP/101-0000017b”, “1?normcid”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:12] Set(“PJSIP/101-0000017b”, “USEROUTCID=”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:13] Set(“PJSIP/101-0000017b”, “EMERGENCYCID=”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:14] Set(“PJSIP/101-0000017b”, “TRUNKOUTCID=3852382040”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf(“PJSIP/101-0000017b”, “1?trunkcid”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (macro-outbound-callerid,s,21)
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf(“PJSIP/101-0000017b”, “1?Set(CALLERID(all)=3852382040)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(“PJSIP/101-0000017b”, “0?Set(CALLERID(all)=)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf(“PJSIP/101-0000017b”, “1?Set(CALLERID(all)=3852382040)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf(“PJSIP/101-0000017b”, “0?Set(CALLERID(all)=101)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:25] ExecIf(“PJSIP/101-0000017b”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:26] ExecIf(“PJSIP/101-0000017b”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:27] Set(“PJSIP/101-0000017b”, “CDR(outbound_cnum)=3852382040”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-outbound-callerid:28] Set(“PJSIP/101-0000017b”, “CDR(outbound_cnam)=”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf(“PJSIP/101-0000017b”, “1?sub-flp-1,s,1()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:1] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:2] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:3] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:4] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:5] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:6] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:7] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:8] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:9] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:10] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:11] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:12] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:13] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:14] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:15] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:16] ExecIf(“PJSIP/101-0000017b”, “0?Set(TARGET_FLP_1=4062064848)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:17] GotoIf(“PJSIP/101-0000017b”, “0?match”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:18] ExecIf(“PJSIP/101-0000017b”, “0?Set(TARGET_FLP_1=4062064848)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:19] GotoIf(“PJSIP/101-0000017b”, “0?match”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:20] ExecIf(“PJSIP/101-0000017b”, “0?Set(TARGET_FLP_1=062064848)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:21] GotoIf(“PJSIP/101-0000017b”, “0?match”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:22] ExecIf(“PJSIP/101-0000017b”, “0?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@sub-flp-1:23] ExecIf(“PJSIP/101-0000017b”, “1?Return()”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:15] Set(“PJSIP/101-0000017b”, “OUTNUM=914062064848”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:16] Set(“PJSIP/101-0000017b”, “custom=PJSIP”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf(“PJSIP/101-0000017b”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf(“PJSIP/101-0000017b”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:19] Macro(“PJSIP/101-0000017b”, “dialout-trunk-predial-hook,”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“PJSIP/101-0000017b”, “”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf(“PJSIP/101-0000017b”, “0?bypass,1”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“PJSIP/101-0000017b”, “1?Set(CONNECTEDLINE(num,i)=14062064848)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf(“PJSIP/101-0000017b”, “1?Set(CONNECTEDLINE(name,i)=CID:3852382040)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:23] ExecIf(“PJSIP/101-0000017b”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)3852382040)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf(“PJSIP/101-0000017b”, “0?customtrunk”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:25] ExecIf(“PJSIP/101-0000017b”, “0?Set(DIAL_TRUNK_OPTIONS=)”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-dialout-trunk:26] Dial(“PJSIP/101-0000017b”, “PJSIP/914062064848@ClearflySIPTrunk,300,Tb(func-apply-sipheaders^s^1,(1))”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] app_stack.c: PJSIP/ClearflySIPTrunk-0000017c Internal Gosub(func-apply-sipheaders,s,1(1)) start
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/ClearflySIPTrunk-0000017c”, “Applying SIP Headers to channel PJSIP/ClearflySIPTrunk-0000017c”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“PJSIP/ClearflySIPTrunk-0000017c”, “TECH=PJSIP”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/ClearflySIPTrunk-0000017c”, “SIPHEADERKEYS=”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@func-apply-sipheaders:4] While(“PJSIP/ClearflySIPTrunk-0000017c”, “0”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] app_while.c: Jumping to priority 12
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/ClearflySIPTrunk-0000017c”, “”) in new stack
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] app_stack.c: Spawn extension (from-pstn, 14062064848, 1) exited non-zero on ‘PJSIP/ClearflySIPTrunk-0000017c’
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] app_stack.c: PJSIP/ClearflySIPTrunk-0000017c Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] app_dial.c: Called PJSIP/914062064848@ClearflySIPTrunk
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] app_dial.c: PJSIP/ClearflySIPTrunk-0000017c answered PJSIP/101-0000017b
[2020-02-02 19:48:53] VERBOSE[32590][C-00000154] bridge_channel.c: Channel PJSIP/ClearflySIPTrunk-0000017c joined ‘simple_bridge’ basic-bridge <7d0afffd-8a4b-4782-92b6-d8c9506ac17d>
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] bridge_channel.c: Channel PJSIP/101-0000017b joined ‘simple_bridge’ basic-bridge <7d0afffd-8a4b-4782-92b6-d8c9506ac17d>
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] bridge_channel.c: Channel PJSIP/101-0000017b left ‘simple_bridge’ basic-bridge <7d0afffd-8a4b-4782-92b6-d8c9506ac17d>
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] app_macro.c: Spawn extension (macro-dialout-trunk, s, 26) exited non-zero on ‘PJSIP/101-0000017b’ in macro ‘dialout-trunk’
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx.c: Spawn extension (from-internal, 14062064848, 7) exited non-zero on ‘PJSIP/101-0000017b’
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/101-0000017b”, “hangupcall”) in new stack
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/101-0000017b”, “1?theend”) in new stack
[2020-02-02 19:48:57] VERBOSE[32590][C-00000154] bridge_channel.c: Channel PJSIP/ClearflySIPTrunk-0000017c left ‘simple_bridge’ basic-bridge <7d0afffd-8a4b-4782-92b6-d8c9506ac17d>
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/101-0000017b”, “0?Set(CDR(recordingfile)=)”) in new stack
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/101-0000017b”, "PJSIP/ClearflySIPTrunk-0000017c montior file= ") in new stack
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“PJSIP/101-0000017b”, “1?skipagi”) in new stack
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“PJSIP/101-0000017b”, “”) in new stack
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘PJSIP/101-0000017b’ in macro ‘hangupcall’
[2020-02-02 19:48:57] VERBOSE[32589][C-00000154] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/101-0000017b’

Apologies for the bad info before!!!

sorvani · 2020-02-03 23:20:19 UTC

jnthodge:

[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] app_dial.c: Called PJSIP/914062064848@ClearflySIPTrunk
[2020-02-02 19:48:53] VERBOSE[32589][C-00000154] app_dial.c: PJSIP/ClearflySIPTrunk-0000017c answered PJSIP/101-0000017b
[2020-02-02 19:48:53] VERBOSE[32590][C-00000154] bridge_channel.c: Channel PJSIP/ClearflySIPTrunk-0000017c joined ‘simple_bridge’ basic-bridge &lt;7d0afffd-8a4b-4782-92b6-d8c9506ac17d&gt;

This call connected.

edit: Use this nextime. https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs-PartII

jnthodge · 2020-02-03 23:50:04 UTC

Ok, that was a strong hint. Every string in the logs “Called PJSIP/XXXXXXXXXXXX” was either an extension/IP or a 9nnnnnnnnnn pattern. I’m so new to this, I wasn’t sure if 9 was required for an outside line or not. It seems the configuration I’ve put in does require it. I’ll try several more experiments and see if I can get a line out.

Thanks for the tip. I’ve got a lot to learn before these logs make much sense to me.

sorvani · 2020-02-04 00:56:05 UTC

You should have a call to India on youR phone bill since 91 is the country for India.

dicko · 2020-02-04 02:43:39 UTC

Not on the Phone Bill as that would need 01191… from Utah , just in the CDR’s,

I’m sure @jnthodge will soon find out he doesn’t need the legacy Centrex dial9 outbound route and it looks like Utah uses 10 digit dialling NXXNXXXXXX for instate calls (801, 385, 435) and 1NXXNXXXXXX for out of state calls , and adding a preliminary 1 on the trunk if Clearfly needs it

Stewart1 · 2020-02-04 02:47:27 UTC

It’s reasonably certain that Clearfly didn’t actually send the call to India, because it was answered in less than a second. I’m guessing that it didn’t like the format or the account wasn’t provisioned for international, so it was routed to an error announcement. The message “… has not been recognized” came from Clearfly (or perhaps their upstream); FreePBX has no message with that text.

Try sending 14062064848, 4062064848 or +14062064848 and see whether the call completes, or you at least get a different error announcement.

tonyclewis · 2020-02-04 03:10:52 UTC

Have you opened ticket with us at Clearly. We are happy to assist with setup and help get things working for you. It’s all Included free of charge with your service with us. Right in the store is a tab to open the ticket.

Stewart1 · 2020-02-04 06:22:58 UTC

Is Clearly IP related to Clearfly Communications? Or, are you just saying that if the OP can’t make it work with Clearfly, he should try Clearly and you’ll help.

Or, is Clearly a customer of Clearfly, like Voxbeam is a customer of Voxbone?

jnthodge · 2020-02-04 06:58:35 UTC

No, in the end, I think it was because I’m a Newby (capital N). In the dial pattern section of the SIP Trunk, I had it configured to require 9 for an outside line. Looking at the docs and forums, I think that’s an antiquated way of configuring it (at least for such a small organization of 6 phones). I now have it working out-bound for both 1-nnnnnnnnnn and nnnnnnnnnn. Made some sample calls and it seemed to work well. Thanks guys, for all your help! A whole new world!

sorvani · 2020-02-04 07:15:43 UTC

Actually,no. The requirement for 011 does not exist unless your carrier requires legacy POTS calling logic.

Stewart1 · 2020-02-04 07:19:37 UTC

It appears that Clearfly accepts 011 but + is also ok.

Stewart1 · 2020-02-04 07:30:41 UTC

Typically, the dial pattern section of a trunk is left empty. Its purpose is to change a number in the format that your users see, to what is required by the trunking provider. Clearfly accepts 7- and 10-digit dialing (and presumably also 11), so it should not be needed.

However, it is common for Outbound Route dial patterns to rewrite numbers to a consistent format, e.g. to change 2382040 to 3852382040, assuming that your area code is 385.

dicko · 2020-02-04 14:29:18 UTC

But the requirement for a ‘meta’ prefix is pretty universal 011 or + in NANP lamd.

BlazeStudios · 2020-02-04 14:37:25 UTC

Dialing 011 to call International is a NANP only scheme. Everywhere else in the world uses 00. The + is also not NANP it is the universal dialing code aka E.164 which is +Country Code-Subscriber. So from the US you either start with 01144 to call the UK or +44 to call the UK.

The fact that US based carriers support and/or require their end users to use E.164 is the US/Canada formatting to the rest of the world.

dicko · 2020-02-04 15:32:37 UTC

I fully understand how international dialing works, grammatically i could have better written ’ +, or 001 in NANP land’

The point is that for historical reasons, in NANP land the conventional 11 digit dialing is inclusive of the meta, this creates an anomoly we can dial 1NXXNXXXXXX and be well routed, and 'often NXXNXXXXXX but in the UK you cant dial 44XXXXXXXXX. as there the ‘meta’ means for internal cals remove the country code and add an initial 0, and for external calls dial 00 then the country code and the national number as follows. In the uk all phone numbers are 10 digits long (with the exception of a few hundred in a small northern town) , all cell phone , (mobile in their vernacular) start with 7 Russia’s country code is 7 . yet there is little confusion because you HAVE to dial 0 for all national numbers and 00 for international ones.

If you store NANPA numbers in your cell phone with 10 digits, there is a problem when exNANPA with ‘long press 0’

So, our job as the broker is to accept any reasonable dial pattern from our users and rewrite them variously to our carriers acceptance rules (they must be complete/unambiguous) , inbound calls need similar normalization. Hopefully we all understand e164 , don’t expect your users to though.

jnthodge · 2020-02-04 19:05:20 UTC

Hi dicko,

Ya, I’m loving that feature, now that I understand how it works. This is my 1st pbx of any type, …ever!