Posts made by Romo

Romo

@manxam said in Outlook Out of Memory to Open Large Folder:

Seeing as how it IS a 64 installation and is not using more than a couple of gigs of ram as stated in your title post, I'd suggest it's a corrupt profile.

Is this connected to IMAP or Exchange? If so, why not spin up a new profile and let the mail sync and see what happens?

The only issues that we ever run into with Outlook are fixed with a new profile which, thankfully, is a quick process -- unless you're using POP3 (kill me now!)

The user has 2 ost files:

And using cached Exchange Mode

She has around 43 user mailboxes as well, thinking this could be whats causing the issues.

Romo

Just to add more info, it is an Office 365 64bit install.

Romo

@pete-s said in Make MS SQL Server 2014 Log Every Query:

Extended Events

@Pete-S happen to know where the default location of the event files are saved?

I created a new session and I believe it is properly showing the queries ran, but if I try to change the place where the file is logged to is doesn't start, but if I leave the default set I can't find the file!!

Romo

@jaredbusch user hasn't answered back to get more logs, but I did call the same number as the user did in the previous log and it rang properly didn't receive the message.

Also I kept checking the logs today and I am seeing a lot of this type of calls as well which end playing the no-service.gsm message. This would be an inbound call right?

[2018-10-04 19:28:56] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [7XXXXXX194@from-trunk:1] Set("SIP/[email protected]", "__FROM_DID=7XXXXXX194") in new stack
[2018-10-04 19:28:56] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [7XXXXXX194@from-trunk:2] NoOp("SIP/[email protected]", "Received an unknown call with DID set to 7XXXXXX194") in new stack
[2018-10-04 19:28:56] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [7XXXXXX194@from-trunk:3] Goto("SIP/[email protected]", "s,a2") in new stack
[2018-10-04 19:28:56] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Goto (from-trunk,s,2)
[2018-10-04 19:28:56] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@from-trunk:2] Answer("SIP/[email protected]", "") in new stack
[2018-10-04 19:28:57] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@from-trunk:3] Log("SIP/[email protected]", "WARNING,Friendly Scanner from 63.209.193.59") in new stack
[2018-10-04 19:28:57] WARNING[1095][C-0000f0dd] Ext. s: Friendly Scanner from 63.209.193.59
[2018-10-04 19:28:57] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@from-trunk:4] Wait("SIP/[email protected]", "2") in new stack
[2018-10-04 19:28:59] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@from-trunk:5] Playback("SIP/[email protected]", "ss-noservice") in new stack
[2018-10-04 19:28:59] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'ss-noservice.gsm' (language 'en')
[2018-10-04 19:29:04] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@from-trunk:6] SayAlpha("SIP/[email protected]", "7XXXXXX194") in new stack
[2018-10-04 19:29:04] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/7.gsm' (language 'en')

[2018-10-04 19:29:05] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/0.gsm' (language 'en')
[2018-10-04 19:29:05] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/4.gsm' (language 'en')
[2018-10-04 19:29:06] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/7.gsm' (language 'en')
[2018-10-04 19:29:07] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/3.gsm' (language 'en')
[2018-10-04 19:29:07] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/8.gsm' (language 'en')
[2018-10-04 19:29:08] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/2.gsm' (language 'en')
[2018-10-04 19:29:09] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/1.gsm' (language 'en')
[2018-10-04 19:29:09] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/9.gsm' (language 'en')
[2018-10-04 19:29:10] VERBOSE[1095][C-0000f0dd] file.c:     -- <SIP/[email protected]> Playing 'digits/4.gsm' (language 'en')
[2018-10-04 19:29:11] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@from-trunk:7] Hangup("SIP/[email protected]", "") in new stack
[2018-10-04 19:29:11] VERBOSE[1095][C-0000f0dd] pbx.c:   == Spawn extension (from-trunk, s, 7) exited non-zero on 'SIP/[email protected]'
[2018-10-04 19:29:11] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [h@from-trunk:1] Macro("SIP/[email protected]", "hangupcall,") in new stack
[2018-10-04 19:29:11] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@macro-hangupcall:1] ExecIf("SIP/[email protected]", "0?Set(CDR(recordingfile)=.)") in new stack
[2018-10-04 19:29:11] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@macro-hangupcall:2] GotoIf("SIP/[email protected]", "1?theend") in new stack
[2018-10-04 19:29:11] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Goto (macro-hangupcall,s,4)
[2018-10-04 19:29:11] VERBOSE[1095][C-0000f0dd] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/[email protected]", "") in new stack
[2018-10-04 19:29:11] VERBOSE[1095][C-0000f0dd] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/[email protected]' in macro 'hangupcall'

Romo

@jaredbusch No other trunk in this location for the moment.

Has the user tried again from their extension?

Yes same message as before

What is the log look like from the other extension?

Here is a working call from my created extension on the PBX

[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [1XXXXXXX012@from-internal:1] Macro("SIP/139-0000fe59", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/139-0000fe59", "TOUCH_MONITOR=1538596218.65142") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:2] Set("SIP/139-0000fe59", "AMPUSER=139") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/139-0000fe59", "0?report") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:4] ExecIf("SIP/139-0000fe59", "1?Set(REALCALLERIDNUM=139)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/139-0000fe59", "AMPUSER=139") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/139-0000fe59", "0?limit") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/139-0000fe59", "AMPUSERCIDNAME=Rodrigo Balcazar") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/139-0000fe59", "0?report") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:9] Set("SIP/139-0000fe59", "AMPUSERCID=139") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:10] Set("SIP/139-0000fe59", "__DIAL_OPTIONS=Ttr") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:11] Set("SIP/139-0000fe59", "CALLERID(all)="Rodrigo Balcazar" <139>") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:12] GotoIf("SIP/139-0000fe59", "0?limit") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:13] ExecIf("SIP/139-0000fe59", "1?Set(GROUP(concurrency_limit)=139)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:14] GotoIf("SIP/139-0000fe59", "1?continue") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (macro-user-callerid,s,27)
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:27] Set("SIP/139-0000fe59", "CALLERID(number)=139") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:28] Set("SIP/139-0000fe59", "CALLERID(name)=Rodrigo Balcazar") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:29] Set("SIP/139-0000fe59", "CDR(cnum)=139") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:30] Set("SIP/139-0000fe59", "CDR(cnam)=Rodrigo Balcazar") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-user-callerid:31] Set("SIP/139-0000fe59", "CHANNEL(language)=en") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [1XXXXXXX012@from-internal:2] Gosub("SIP/139-0000fe59", "sub-record-check,s,1(out,1XXXXXXX012,dontcare)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:1] GotoIf("SIP/139-0000fe59", "0?initialized") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:2] Set("SIP/139-0000fe59", "__REC_STATUS=INITIALIZED") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:3] Set("SIP/139-0000fe59", "NOW=1538596218") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:4] Set("SIP/139-0000fe59", "__DAY=03") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:5] Set("SIP/139-0000fe59", "__MONTH=10") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:6] Set("SIP/139-0000fe59", "__YEAR=2018") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:7] Set("SIP/139-0000fe59", "__TIMESTR=20181003-195018") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:8] Set("SIP/139-0000fe59", "__FROMEXTEN=139") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:9] Set("SIP/139-0000fe59", "__MON_FMT=wav") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:10] NoOp("SIP/139-0000fe59", "Recordings initialized") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:11] ExecIf("SIP/139-0000fe59", "0?Set(ARG3=dontcare)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:12] Set("SIP/139-0000fe59", "REC_POLICY_MODE_SAVE=") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:13] ExecIf("SIP/139-0000fe59", "0?Set(REC_STATUS=NO)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:14] GotoIf("SIP/139-0000fe59", "3?checkaction") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (sub-record-check,s,17)
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@sub-record-check:17] GotoIf("SIP/139-0000fe59", "1?sub-record-check,out,1") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (sub-record-check,out,1)
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [out@sub-record-check:1] NoOp("SIP/139-0000fe59", "Outbound Recording Check from 139 to 1XXXXXXX012") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [out@sub-record-check:2] Set("SIP/139-0000fe59", "RECMODE=dontcare") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [out@sub-record-check:3] ExecIf("SIP/139-0000fe59", "1?Goto(routewins)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (sub-record-check,out,7)
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [out@sub-record-check:7] Gosub("SIP/139-0000fe59", "recordcheck,1(dontcare,out,1XXXXXXX012)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/139-0000fe59", "Starting recording check against dontcare") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [recordcheck@sub-record-check:2] Goto("SIP/139-0000fe59", "dontcare") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (sub-record-check,recordcheck,3)
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [recordcheck@sub-record-check:3] Return("SIP/139-0000fe59", "") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [out@sub-record-check:8] Return("SIP/139-0000fe59", "") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [1XXXXXXX012@from-internal:3] Set("SIP/139-0000fe59", "MOHCLASS=default") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [1XXXXXXX012@from-internal:4] ExecIf("SIP/139-0000fe59", "1?Set(TRUNKCIDOVERRIDE="PAL" <7046372140>)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [1XXXXXXX012@from-internal:5] Set("SIP/139-0000fe59", "_NODEST=") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [1XXXXXXX012@from-internal:6] Macro("SIP/139-0000fe59", "dialout-trunk,7,1XXXXXXX012,,off") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:1] Set("SIP/139-0000fe59", "DIAL_TRUNK=7") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/139-0000fe59", "0?sub-pincheck,s,1()") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/139-0000fe59", "0?disabletrunk,1") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:4] Set("SIP/139-0000fe59", "DIAL_NUMBER=1XXXXXXX012") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:5] Set("SIP/139-0000fe59", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:6] Set("SIP/139-0000fe59", "OUTBOUND_GROUP=OUT_7") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/139-0000fe59", "1?nomax") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (macro-dialout-trunk,s,9)
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/139-0000fe59", "0?skipoutcid") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:10] Set("SIP/139-0000fe59", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/139-0000fe59", "outbound-callerid,7") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/139-0000fe59", "0?Set(CALLERPRES()=)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/139-0000fe59", "0?Set(REALCALLERIDNUM=139)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/139-0000fe59", "1?normcid") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/139-0000fe59", "USEROUTCID=") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/139-0000fe59", "EMERGENCYCID=") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/139-0000fe59", "TRUNKOUTCID=7XXXXXXX140") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/139-0000fe59", "1?trunkcid") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (macro-outbound-callerid,s,14)
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/139-0000fe59", "1?Set(CALLERID(all)=7XXXXXXX140)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/139-0000fe59", "0?Set(CALLERID(all)=)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/139-0000fe59", "1?Set(CALLERID(all)=PAL <7XXXXXXX140>)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/139-0000fe59", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:18] Set("SIP/139-0000fe59", "CDR(outbound_cnum)=7XXXXXXX140") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-outbound-callerid:19] Set("SIP/139-0000fe59", "CDR(outbound_cnam)=PAL") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/139-0000fe59", "0?sub-flp-7,s,1()") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:13] Set("SIP/139-0000fe59", "OUTNUM=1XXXXXXX012") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:14] Set("SIP/139-0000fe59", "custom=SIP/Fibrant2140") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/139-0000fe59", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/139-0000fe59", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:17] Macro("SIP/139-0000fe59", "dialout-trunk-predial-hook,") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/139-0000fe59", "") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/139-0000fe59", "0?bypass,1") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/139-0000fe59", "1?Set(CONNECTEDLINE(num,i)=1XXXXXXX012)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/139-0000fe59", "1?Set(CONNECTEDLINE(name,i)=CID:7XXXXXXX140)") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/139-0000fe59", "0?customtrunk") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-dialout-trunk:22] Dial("SIP/139-0000fe59", "SIP/Fibrant2140/1XXXXXXX012,300,Tt") in new stack
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] netsock2.c:   == Using SIP RTP TOS bits 184
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] netsock2.c:   == Using SIP RTP CoS mark 5
[2018-10-03 19:50:18] VERBOSE[27885][C-0000efa3] app_dial.c:     -- Called SIP/Fibrant2140/1XXXXXXX012
[2018-10-03 19:50:20] VERBOSE[27885][C-0000efa3] app_dial.c:     -- SIP/Fibrant2140-0000fe5a is making progress passing it to SIP/139-0000fe59
[2018-10-03 19:50:21] VERBOSE[27885][C-0000efa3] app_dial.c:     -- SIP/Fibrant2140-0000fe5a answered SIP/139-0000fe59
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [h@macro-dialout-trunk:1] Macro("SIP/139-0000fe59", "hangupcall,") in new stack
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-hangupcall:1] ExecIf("SIP/139-0000fe59", "0?Set(CDR(recordingfile)=.wav)") in new stack
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-hangupcall:2] GotoIf("SIP/139-0000fe59", "1?theend") in new stack
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] pbx.c:     -- Goto (macro-hangupcall,s,4)
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/139-0000fe59", "") in new stack
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/139-0000fe59' in macro 'hangupcall'
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] pbx.c:   == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/139-0000fe59'
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] app_macro.c:   == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'SIP/139-0000fe59' in macro 'dialout-trunk'
[2018-10-03 19:50:27] VERBOSE[27885][C-0000efa3] pbx.c:   == Spawn extension (from-internal, 1XXXXXXX012, 6) exited non-zero on 'SIP/139-0000fe59'

Are you sure your trunk settings allow enough simultaneous calls?

I believe so, no other user has complained about receiving that message.

@scottalanmiller Would you happend to know how many concurrent calls can the trunk handle?

Romo

@jaredbusch Its all there now, thought the other part wasn't necessary sorry

Romo

Part of the log:

[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [1XXXXXXX276@from-internal:1] Macro("SIP/7212-0000fd44", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/7212-0000fd44", "TOUCH_MONITOR=1538579584.64861") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:2] Set("SIP/7212-0000fd44", "AMPUSER=7212") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/7212-0000fd44", "0?report") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:4] ExecIf("SIP/7212-0000fd44", "1?Set(REALCALLERIDNUM=7212)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/7212-0000fd44", "AMPUSER=7212") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/7212-0000fd44", "0?limit") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/7212-0000fd44", "AMPUSERCIDNAME=USERNAME") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/7212-0000fd44", "0?report") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:9] Set("SIP/7212-0000fd44", "AMPUSERCID=7212") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:10] Set("SIP/7212-0000fd44", "__DIAL_OPTIONS=Ttr") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:11] Set("SIP/7212-0000fd44", "CALLERID(all)="USERNAME" <7212>") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:12] GotoIf("SIP/7212-0000fd44", "0?limit") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:13] ExecIf("SIP/7212-0000fd44", "1?Set(GROUP(concurrency_limit)=7212)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:14] GotoIf("SIP/7212-0000fd44", "1?continue") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (macro-user-callerid,s,27)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:27] Set("SIP/7212-0000fd44", "CALLERID(number)=7212") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:28] Set("SIP/7212-0000fd44", "CALLERID(name)=USERNAME") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:29] Set("SIP/7212-0000fd44", "CDR(cnum)=7212") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:30] Set("SIP/7212-0000fd44", "CDR(cnam)=USERNAME") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-user-callerid:31] Set("SIP/7212-0000fd44", "CHANNEL(language)=en") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [1XXXXXXX276@from-internal:2] Gosub("SIP/7212-0000fd44", "sub-record-check,s,1(out,1XXXXXXX276,dontcare)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:1] GotoIf("SIP/7212-0000fd44", "0?initialized") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:2] Set("SIP/7212-0000fd44", "__REC_STATUS=INITIALIZED") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:3] Set("SIP/7212-0000fd44", "NOW=1538579584") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:4] Set("SIP/7212-0000fd44", "__DAY=03") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:5] Set("SIP/7212-0000fd44", "__MONTH=10") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:6] Set("SIP/7212-0000fd44", "__YEAR=2018") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:7] Set("SIP/7212-0000fd44", "__TIMESTR=20181003-151304") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:8] Set("SIP/7212-0000fd44", "__FROMEXTEN=7212") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:9] Set("SIP/7212-0000fd44", "__MON_FMT=wav") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:10] NoOp("SIP/7212-0000fd44", "Recordings initialized") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:11] ExecIf("SIP/7212-0000fd44", "0?Set(ARG3=dontcare)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:12] Set("SIP/7212-0000fd44", "REC_POLICY_MODE_SAVE=") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:13] ExecIf("SIP/7212-0000fd44", "0?Set(REC_STATUS=NO)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:14] GotoIf("SIP/7212-0000fd44", "3?checkaction") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (sub-record-check,s,17)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@sub-record-check:17] GotoIf("SIP/7212-0000fd44", "1?sub-record-check,out,1") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (sub-record-check,out,1)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [out@sub-record-check:1] NoOp("SIP/7212-0000fd44", "Outbound Recording Check from 7212 to 1XXXXXXX276") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [out@sub-record-check:2] Set("SIP/7212-0000fd44", "RECMODE=dontcare") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [out@sub-record-check:3] ExecIf("SIP/7212-0000fd44", "1?Goto(routewins)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (sub-record-check,out,7)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [out@sub-record-check:7] Gosub("SIP/7212-0000fd44", "recordcheck,1(dontcare,out,1XXXXXXX276)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/7212-0000fd44", "Starting recording check against dontcare") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [recordcheck@sub-record-check:2] Goto("SIP/7212-0000fd44", "dontcare") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (sub-record-check,recordcheck,3)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [recordcheck@sub-record-check:3] Return("SIP/7212-0000fd44", "") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [out@sub-record-check:8] Return("SIP/7212-0000fd44", "") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [1XXXXXXX276@from-internal:3] Set("SIP/7212-0000fd44", "MOHCLASS=default") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [1XXXXXXX276@from-internal:4] ExecIf("SIP/7212-0000fd44", "0?Set(TRUNKCIDOVERRIDE="PAL" <7xxxxxx140>)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [1XXXXXXX276@from-internal:5] Set("SIP/7212-0000fd44", "_NODEST=") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [1XXXXXXX276@from-internal:6] Macro("SIP/7212-0000fd44", "dialout-trunk,7,1XXXXXXX276,,off") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:1] Set("SIP/7212-0000fd44", "DIAL_TRUNK=7") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/7212-0000fd44", "0?sub-pincheck,s,1()") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/7212-0000fd44", "0?disabletrunk,1") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:4] Set("SIP/7212-0000fd44", "DIAL_NUMBER=1XXXXXXX276") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:5] Set("SIP/7212-0000fd44", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:6] Set("SIP/7212-0000fd44", "OUTBOUND_GROUP=OUT_7") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/7212-0000fd44", "1?nomax") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (macro-dialout-trunk,s,9)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/7212-0000fd44", "0?skipoutcid") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:10] Set("SIP/7212-0000fd44", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/7212-0000fd44", "outbound-callerid,7") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/7212-0000fd44", "0?Set(CALLERPRES()=)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/7212-0000fd44", "0?Set(REALCALLERIDNUM=7212)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/7212-0000fd44", "1?normcid") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/7212-0000fd44", "USEROUTCID=USERNAME <7XXXXXX169>") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/7212-0000fd44", "EMERGENCYCID=") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/7212-0000fd44", "TRUNKOUTCID=7xxxxxx140") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/7212-0000fd44", "1?trunkcid") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (macro-outbound-callerid,s,14)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/7212-0000fd44", "1?Set(CALLERID(all)=7xxxxxx140)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/7212-0000fd44", "1?Set(CALLERID(all)=USERNAME <7XXXXXX169>)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/7212-0000fd44", "0?Set(CALLERID(all)=)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/7212-0000fd44", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:18] Set("SIP/7212-0000fd44", "CDR(outbound_cnum)=7XXXXXX169") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-outbound-callerid:19] Set("SIP/7212-0000fd44", "CDR(outbound_cnam)=USERNAME") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/7212-0000fd44", "0?sub-flp-7,s,1()") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:13] Set("SIP/7212-0000fd44", "OUTNUM=1XXXXXXX276") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:14] Set("SIP/7212-0000fd44", "custom=SIP/Fibrant2140") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/7212-0000fd44", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/7212-0000fd44", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:17] Macro("SIP/7212-0000fd44", "dialout-trunk-predial-hook,") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/7212-0000fd44", "") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/7212-0000fd44", "0?bypass,1") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/7212-0000fd44", "1?Set(CONNECTEDLINE(num,i)=1XXXXXXX276)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/7212-0000fd44", "1?Set(CONNECTEDLINE(name,i)=CID:7XXXXXX169)") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/7212-0000fd44", "0?customtrunk") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:22] Dial("SIP/7212-0000fd44", "SIP/Fibrant2140/1XXXXXXX276,300,Tt") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] netsock2.c:   == Using SIP RTP TOS bits 184
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] netsock2.c:   == Using SIP RTP CoS mark 5
- - - -
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] app_dial.c:     -- Called SIP/Fibrant2140/1XXXXXXX276

[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] app_dial.c:   == Everyone is busy/congested at this time (1:0/0/1)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/7212-0000fd44", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 1") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/7212-0000fd44", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/7212-0000fd44", "RC=1") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/7212-0000fd44", "1,1") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (macro-dialout-trunk,1,1)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [1@macro-dialout-trunk:1] Goto("SIP/7212-0000fd44", "s-INVALIDNMBR,1") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Goto (macro-dialout-trunk,s-INVALIDNMBR,1)
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s-INVALIDNMBR@macro-dialout-trunk:1] NoOp("SIP/7212-0000fd44", "Dial failed due to trunk reporting Address Incomplete - giving up") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s-INVALIDNMBR@macro-dialout-trunk:2] Progress("SIP/7212-0000fd44", "") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s-INVALIDNMBR@macro-dialout-trunk:3] Playback("SIP/7212-0000fd44", "ss-noservice,noanswer") in new stack
[2018-10-03 15:13:04] VERBOSE[8513][C-0000eeec] file.c:     -- <SIP/7212-0000fd44> Playing 'ss-noservice.gsm' (language 'en')
[2018-10-03 15:13:09] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [s-INVALIDNMBR@macro-dialout-trunk:4] Busy("SIP/7212-0000fd44", "20") in new stack
[2018-10-03 15:13:09] WARNING[8513][C-0000eeec] channel.c: Prodding channel 'SIP/7212-0000fd44' failed
[2018-10-03 15:13:09] VERBOSE[8513][C-0000eeec] app_macro.c:   == Spawn extension (macro-dialout-trunk, s-INVALIDNMBR, 4) exited non-zero on 'SIP/7212-0000fd44' in macro 'dialout-trunk'
[2018-10-03 15:13:09] VERBOSE[8513][C-0000eeec] pbx.c:   == Spawn extension (from-internal, 1XXXXXXX276, 6) exited non-zero on 'SIP/7212-0000fd44'
[2018-10-03 15:13:09] VERBOSE[8513][C-0000eeec] pbx.c:     -- Executing [h@from-internal:1] Hangup("SIP/7212-0000fd44", "") in new stack
[2018-10-03 15:13:09] VERBOSE[8513][C-0000eeec] pbx.c:   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/7212-0000fd44'

Romo

So a using is reporting that when dialing outbound from his extension, he is getting the mesage "The number you have dialed is not in service ....". He says if he moves to another extension he can make the calls properly. He has tried calling coworkers and clients all with the same errors.

Any idea what can be causing this? I can't replicate the error message from another extension in the same pbx. Dialing is done using 1-area code-number

Romo

A DNAT rule was the culprit of everything, it was redirecting the traffic and not letting it reach WAN_LOCAL.

FINALLY SOLVED!!!!!!!!!!!!!!!!!!!!!!!!!

As reminder for anyone that could encounter a similar issue:
DNAT rules are evaluated before firewall rules.

Romo

Yeah, I didn't find strange it reverted to using the old config, what seems strange is that it is not even working on a firmware version it used to work with and with a config that used to work as well.

Really thought the downgrade would have made it work again.

Romo

What's really strange is that with the downgrade, it even returned to the original configuration the one that was properly working before and still nothing.

Romo

Downgraded to 1.10.5 and still can't establish a proper connection or get any other type of log.

Version:      v1.10.5
Build ID:     5098915
Build on:     06/22/18 13:55
Copyright:    2012-2018 Ubiquiti Networks, Inc.
HW model:     EdgeRouter Lite 3-Port
HW S/N:       788A2041AA25
Uptime:       01:19:49 up 1 day,  4:46,  1 user,  load average: 0.23, 0.16, 0.14

Could this mean the device is having some sort of problem or could it be Internet Service Provider caused????

Romo

Email trace in https://protection.office.com/#/messagetrace

Romo

@scottalanmiller The most login prompts are using it in Chrome.

Chrome has a bug regarding the websockets protocol and NTLM authentication. This effects the following tools: Events, PowerShell, Remote Desktop.
https://bugs.chromium.org/p/chromium/issues/detail?id=423609

Romo

@pete-s Those where taken using chrome but all browsers have the tools.

Here are couple screenshots from firefox webdev tools
0_1536977899506_Screenshot from 2018-09-14 21-15-09.png

Performance Tab
0_1536977990636_Screenshot from 2018-09-14 21-19-22.png

From the google chrome dev tools you can even get an audit using Lighthouse

You get a report like this:
0_1536978363389_Screenshot from 2018-09-14 21-23-46.png

Romo

For starters use the dev tools of your browser:

Here is the performance profile from mangolassi on my computer
alt text

Network performance of the assets, as you can see many things are already cached on my disk.
alt text

Romo

About 640 days of uptime on the server, so that could be a reason.

Romo

@taurex Site to site is working properly

Romo

@taurex just added the ipsec-interfaces setting even though it is deprecated according to the documentation and still nothing :disappointed_face:

Romo

@taurex said in EdgeRouter L2TP VPN can't pass IKE phase 1:

@romo I don't see ipsec match-ipsec set in your L2TP rule.

Do you also have WAN_IN firewall rules set up?

WAN_IN

name WAN_IN {
     default-action drop
     description "WAN to internal"
     rule 10 {
         action accept
         description "Allow established/related"
         state {
             established enable
             related enable
         }
     }
     rule 20 {
         action drop
         description "Drop invalid state"
         state {
             invalid enable
         }
     }
     rule 21 {
         action accept
         description "Web Traffic to Help Desk"
         destination {
             group {
                 address-group Helpdesk_internal
                 port-group HTTP_and_HTTPS
             }
         }
         log disable
         protocol tcp
         state {
             established enable
             invalid disable
             new enable
             related enable
         }
     }
     rule 22 {
         action accept
         description "Web Traffic to DevTestWeb"
         destination {
             group {
                 address-group DevTestWeb_internal
                 port-group HTTP_and_HTTPS
             }
         }
         log disable
         protocol tcp
         state {
             established enable
             invalid disable
             new enable
             related enable
         }
     }
     rule 23 {
         action accept
         description "Web Traffic to Demo"
         destination {
             group {
                 address-group Demo_internal
                 port-group HTTP_and_HTTPS
             }
         }
         log disable
         protocol tcp
         state {
             established enable
             invalid disable
             new enable
             related enable
         }
     }
     rule 24 {
         action accept
         description "Web Traffic to QB"
         destination {
             group {
                 address-group QB_Int
                 port-group RDP
             }
         }
         log disable
         protocol tcp
         state {
             established enable
             invalid disable
             new enable
             related enable
         }
     }
 }

Added the ipsec match-ipsec on WAN_LOCAL still nothing.

show firewall name WAN_LOCAL
 default-action drop
 description "WAN to router"
 rule 10 {
     action accept
     description "Allow established/related"
     state {
         established enable
         related enable
     }
 }
 rule 20 {
     action drop
     description "Drop invalid state"
     state {
         invalid enable
     }
 }
 rule 30 {
     action accept
     description "Allow iCMP"
     disable
     log disable
     protocol icmp
 }
 rule 40 {
     action accept
     description "Allow IKE for VPN"
     destination {
         port 500
     }
     log disable
     protocol udp
 }
 rule 50 {
     action accept
     description "Allow ESP for VPN"
     log disable
     protocol esp
 }
 rule 60 {
     action accept
     description "Allow NAT-T for VPN"
     destination {
         port 4500
     }
     log disable
     protocol udp
 }
 rule 70 {
     action accept
     description "Allow L2TP for VPN"
     destination {
         port 1701
     }
     ipsec {
         match-ipsec
     }
     log disable
     protocol udp
 }