Διακοπές ομιλίας σε VoIP

[KostasGe]

Καλησπέρα,

έχω μια συνδρομή ΟΤΕ ADSL double play με VoIP τηλεφωνία και 24Mbs σύνδεση. Πίσω από το γνωστό Speedport Entry 2i
έχω στήσει ένα μίνι τηλ κέντρο σε Free PBX πάνω σε ένα raspberry 3 Model B.

Ακολουθώντας τις οδηγίες που βρήκα εδώ:

https://www.adslgr.com/forum/threads...24#post6110724

ρύθμισα τα Trunks settings, και όλα φαινομενικά λειτουργούν όπως έπρεπε εκτός από κάποιες λίγες αλλά ενοχλητικές διακοπές στην τηλεφωνία οι οποίες εμφανίζονται είτε με διακοπή της κλίσης και μήνυμα στην οθόνη του SPA941 "Call Ended" είτε με το μήνυμα "All Circuits are Busy Now...." αν υπάρξει προσπάθεια για κλίση κάποιου αριθμού την ώρα που το σύστημα έχει ξεσυχγρονιστεί. Tο σύστημα επανέρχεται εντός 1-2 λεπτών από μόνο του, και οι κλίσεις μπορούν τότε να γίνουν κανονικά.

Εδώ θα ήθελα την βοήθεια σας.

[deniSun]

Καλησπέρα,

έχω μια συνδρομή ΟΤΕ ADSL double play με VoIP τηλεφωνία και 24Mbs σύνδεση. Πίσω από το γνωστό Speedport Entry 2i
έχω στήσει ένα μίνι τηλ κέντρο σε Free PBX πάνω σε ένα raspberry 3 Model B.

Ακολουθώντας τις οδηγίες που βρήκα εδώ:

https://www.adslgr.com/forum/threads...24#post6110724

ρύθμισα τα Trunks settings, και όλα φαινομενικά λειτουργούν όπως έπρεπε εκτός από κάποιες λίγες αλλά ενοχλητικές διακοπές στην τηλεφωνία οι οποίες εμφανίζονται είτε με διακοπή της κλίσης και μήνυμα στην οθόνη του SPA941 "Call Ended" είτε με το μήνυμα "All Circuits are Busy Now...." αν υπάρξει προσπάθεια για κλίση κάποιου αριθμού την ώρα που το σύστημα έχει ξεσυχγρονιστεί. Tο σύστημα επανέρχεται εντός 1-2 λεπτών από μόνο του, και οι κλίσεις μπορούν τότε να γίνουν κανονικά.

Εδώ θα ήθελα την βοήθεια σας.

Μήπως το rasp σου κάνει νερά.
Είναι πολύ αναξιόπιστο για 24/7 εφαρμογές.

[KostasGe]

Δεν ξέρω παιδιά, είναι το rasp ή παίζει κάτι στην διασύνδεση με ΟΤΕ?

[jkoukos]

Προσωπικά το έχω στημένο περίπου 1 μήνα στο ίδιο Rpi και δεν έχω αντιμετωπίσει κανένα αντίστοιχο πρόβλημα.
Δείχνει να χάνεται η σύνδεση με τον IMS server του ΟΤΕ, οπότε πρέπει να ψαχθούν οι ρυθμίσεις του PBX και φυσικά να έχει σταθερή ΙΡ και ενεργό port forwarding.

[KostasGe]

Έχω δώσει σταθερή IP και Port forwarding έχω κάνει στα SIP 5060 5061 και στα RTP από 20.000 εως 40.000. Έχω κλείσει και το ALG του SIP από το ρούτερ. Πως να το προχωρήσουμε απο δω και περα?

[mondeo]

Έχω δώσει σταθερή IP και Port forwarding έχω κάνει στα SIP 5060 5061 και στα RTP από 20.000 εως 40.000. Έχω κλείσει και το ALG του SIP από το ρούτερ. Πως να το προχωρήσουμε απο δω και περα?

Έχω μία παρόμοια υλοποίηση πάνω σε OTE Voip.
Όσο η γραμμή ήταν ISDN όλα έπαιζαν άψογα (η γραμμή μπαίνει στο Pi μέσω ενός ΗΤ503)
Την Τετάρτη έγινε μετάβαση σε Voip και μας εγκατέστησαν το Oxygen.
Αποτέλεσμα; ΧΑΟΣ!!!!
Οι κλήσεις κοβόντουσαν, δεν μπορούσαμε να πάρουμε κι όταν παίρναμε έβγαινε λάθος αριθμός!!!
Ήρθε λοιπόν σήμερα άλλο συνεργείο, έβγαλε το Oxygen κι έβαλε το Speedport.
Είπαν ότι το Oxygen κάνει τέτοιες λακαμίες.
Προσωρινά έβγαλα το κέντρο να δούμε πως θα πάει η γραμμή.
Είπαν επίσης ότι επίσημα χρησιμοποιούν RTP Ports από 10.000-12.000, ενώ ανεπίσημα από 8.500-12.000, και αυτό το port range μας είπαν να το έχουμε καθαρό χωρίς port forwarding.
Στην 8888 είχα ένα καταγραφικό και το έβαλα στην 8400.
Είναι μεγάλο πάντως μειονέκτημα το ότι έχουν ρίξει την τηλεφωνία στο 8/35.
Στη Voda την δουλεύουν στο 8/38 και δεν έχει κανένα πρόβλημα.
Δεν σκέφτομαι να στήσω sip trunk.Θα το αφήσω να παίζει με το ΗΤ503.
Αυτά.

[KostasGe]

Σήμερα το δοκίμασα και με το Free PBX στημένο σε laptop (όχι πια raspberry) αλλά παρουσιάζει ακριβώς τα ίδια προβλήματα. Άρα φταει κάτι από τις δικές μου ρυθμίσεις ή από ΟΤΕ;

[deniSun]

Σήμερα το δοκίμασα και με το Free PBX στημένο σε laptop (όχι πια raspberry) αλλά παρουσιάζει ακριβώς τα ίδια προβλήματα. Άρα φταει κάτι από τις δικές μου ρυθμίσεις ή από ΟΤΕ;

Για να δεις αν είναι θέμα ΟΤΕ πρέπει να ρίξεις πάνω τον εξοπλισμό που σου έδωσαν και να δεις αν σου το κάνει και εκεί.

[astbox]

Επίσης τσιγκουνεύεσαι τα logs από το cli, ανέβασε καμιά δοκιμούλα να βλέπουμε τι γίνεται, με τις περιγραφές δεν κάνουμε δουλειά!

asterisk -rvvvd από ssh και ανέβασε τι σου βγάζει.

[KostasGe]

Με την τηλεφωνία στο SpeedPort παίζει καλά. Με το freepbx κάνει προβλήματα.

- - - Updated - - -

Επίσης τσιγκουνεύεσαι τα logs από το cli, ανέβασε καμιά δοκιμούλα να βλέπουμε τι γίνεται, με τις περιγραφές δεν κάνουμε δουλειά!

asterisk -rvvvd από ssh και ανέβασε τι σου βγάζει.

Αυτό είναι από τα log files του free PBX, δεν ξέρω αν είναι αυτό που χρειάζεσαι αλλά ρίξε μια ματιά. Είναι από το raspberry όχι από το PC. Από ssh θα προσπαθήσω αύριο.

[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/101-00000009", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:18] Macro("PJSIP/101-00000009", "dialout-trunk-predial-hook,") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/101-00000009", "") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:19] GotoIf("PJSIP/101-00000009", "0?bypass,1") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/101-00000009", "1?Set(CONNECTEDLINE(num,i)=21098xxxxx)") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/101-00000009", "1?Set(CONNECTEDLINE(name,i)=CID:21098xxxxx)") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/101-00000009", "0?Set(CONNECTEDLINE(name,i)=CIDHidden)21098xxxxx)") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:23] GotoIf("PJSIP/101-00000009", "0?customtrunk") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:24] Dial("PJSIP/101-00000009", "SIP/OTE_VoIP/21098xxxxx,300,Tb(func-apply-sipheaders^s^1)") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] netsock2.c: Using SIP RTP TOS bits 184
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] netsock2.c: Using SIP RTP CoS mark 5
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] app_stack.c: SIP/OTE_VoIP-00000007 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/OTE_VoIP-00000007", "Applying SIP Headers to channel") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/OTE_VoIP-00000007", "SIPHEADERKEYS=") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/OTE_VoIP-00000007", "0") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] app_while.c: Jumping to priority 7
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/OTE_VoIP-00000007", "") in new stack
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] app_stack.c: Spawn extension (from-trunk-sip-OTE_VoIP, 21098xxxxx, 1) exited non-zero on 'SIP/OTE_VoIP-00000007'
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] app_stack.c: SIP/OTE_VoIP-00000007 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] chan_sip.c: Audio is at 19432
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] chan_sip.c: Adding codec ulaw to SDP
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] chan_sip.c: Adding codec alaw to SDP
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] chan_sip.c: Adding codec gsm to SDP
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] chan_sip.c: Adding codec g726 to SDP
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] chan_sip.c: Adding codec g722 to SDP
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] chan_sip.c: Reliably Transmitting (NAT) to 195.167.16.9:5060:
INVITE sip:21098xxxxx@ims.otenet.gr SIP/2.0
Via: SIP/2.0/UDP 2.87.76.53:5160;branch=z9hG4bK727329a9;rport
Max-Forwards: 70
From: "The Name of my Bussiness" <sip:+3021098xxxxx@ims.otenet.gr:5160>;tag=as758c8c24
To: <sip:21098xxxxx@ims.otenet.gr>
Contact: <sip:+3021098xxxxx@2.87.76.53:5160>
Call-ID: 76c354972013f6470bba8e3873a7ac79@ims.otenet.gr
CSeq: 102 INVITE
User-Agent: FPBX-14.0.2.18(13.20.0)
Date: Fri, 20 Apr 2018 15:45:17 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 348

v=0
o=root 394159977 394159977 IN IP4 2.87.76.53
s=Asterisk PBX 13.20.0
c=IN IP4 2.87.76.53
t=0 0
m=audio 19432 RTP/AVP 0 8 3 111 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

---
[2018-04-20 18:45:17] VERBOSE[7911][C-0000002f] app_dial.c: Called SIP/OTE_VoIP/21098xxxxx
[2018-04-20 18:45:17] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:195.167.16.9:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 2.87.76.53:5160;branch=z9hG4bK727329a9;rport=5160
Call-ID: 76c354972013f6470bba8e3873a7ac79@ims.otenet.gr
From: "The Name of my Bussiness"<sip:+3021098xxxxx@ims.otenet.gr:5160>;tag=as758c8c24
To: <sip:21098xxxxx@ims.otenet.gr>
CSeq: 102 INVITE
Content-Length: 0

<------------->
[2018-04-20 18:45:17] VERBOSE[1366] chan_sip.c: --- (7 headers 0 lines) ---
[2018-04-20 18:45:18] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:195.167.16.9:5060 --->
INVITE sip:+3021098xxxxx@2.87.76.53:5160 SIP/2.0
Via: SIP/2.0/UDP 195.167.16.9:5060;branch=z9hG4bK90h38495u9u9e8oo8hhz4to36T17187
Call-ID: asbc6excwf16vfdf1wzyuwd6ye6xx00z6d0e....ims.otenet.gr.79
From: <sip:21098xxxxx@ims.otenet.gr;user=phone>;tag=sbc05086f1zszdn-CC-79
To: <sip:21098xxxxx@ims.otenet.gr>
CSeq: 1 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY,INFO,PUBLISH,MESSAGE
Contact: <sip:+3021098xxxxx@195.167.16.9:5060>
Max-Forwards: 64
P-Asserted-Identity: <sip:+3021098xxxxx@ims.otenet.gr>,<tel:+3021098xxxxx>
P-Called-Party-ID: <tel:+3021098xxxxx>
Content-Length: 335
Content-Type: application/sdp

v=0
o=- 46865800 46865800 IN IP4 195.167.17.9
s=SBC call
c=IN IP4 195.167.17.9
t=0 0
m=audio 10758 RTP/AVP 0 8 3 111 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
<------------->
[2018-04-20 18:45:18] VERBOSE[1366] chan_sip.c: --- (13 headers 16 lines) ---
[2018-04-20 18:45:18] VERBOSE[1366] chan_sip.c: Sending to 195.167.16.9:5060 (NAT)
[2018-04-20 18:45:18] VERBOSE[1366][C-00000030] chan_sip.c: Sending to 195.167.16.9:5060 (NAT)
[2018-04-20 18:45:18] VERBOSE[1366][C-00000030] chan_sip.c: Using INVITE request as basis request - asbc6excwf16vfdf1wzyuwd6ye6xx00z6d0e....ims.otenet.gr.79
[2018-04-20 18:45:18] VERBOSE[1366][C-00000030] chan_sip.c: Found peer '+3021098xxxxx@ims.otenet.gr' for '21098xxxxx' from 195.167.16.9:5060
[2018-04-20 18:45:18] VERBOSE[1366][C-00000030] chan_sip.c:
<--- Reliably Transmitting (NAT) to 195.167.16.9:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 195.167.16.9:5060;branch=z9hG4bK90h38495u9u9e8oo8hhz4to36T17187;received=195.167.16.9;rport=5060
From: <sip:21098xxxxx@ims.otenet.gr;user=phone>;tag=sbc05086f1zszdn-CC-79
To: <sip:21098xxxxx@ims.otenet.gr>;tag=as56553425
Call-ID: asbc6excwf16vfdf1wzyuwd6ye6xx00z6d0e....ims.otenet.gr.79
CSeq: 1 INVITE
Server: FPBX-14.0.2.18(13.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="161252f7"
Content-Length: 0


<------------>
[2018-04-20 18:45:18] VERBOSE[1366][C-00000030] chan_sip.c: Scheduling destruction of SIP dialog 'asbc6excwf16vfdf1wzyuwd6ye6xx00z6d0e@ATS.athe-ATS1.ims.otenet.gr.79' in 32000 ms (Method: INVITE)
[2018-04-20 18:45:18] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:195.167.16.9:5060 --->
ACK sip:+3021098xxxxx@2.87.76.53:5160 SIP/2.0
Via: SIP/2.0/UDP 195.167.16.9:5060;branch=z9hG4bK90h38495u9u9e8oo8hhz4to36T17187;received=195.167.16.9;rport=5060
Call-ID: asbc6excwf16vfdf1wzyuwd6ye6xx00z6d0e....ims.otenet.gr.79
From: <sip:21098xxxxx@ims.otenet.gr;user=phone>;tag=sbc05086f1zszdn-CC-79
To: <sip:21098xxxxx@ims.otenet.gr>;tag=as56553425
CSeq: 1 ACK
Max-Forwards: 70
Content-Length: 0

<------------->
[2018-04-20 18:45:18] VERBOSE[1366] chan_sip.c: --- (8 headers 0 lines) ---
[2018-04-20 18:45:18] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:195.167.16.9:5060 --->
SIP/2.0 500 Server Internal Error
Via: SIP/2.0/UDP 2.87.76.53:5160;branch=z9hG4bK727329a9;rport=5160
Call-ID: 76c354972013f6470bba8e3873a7ac79@ims.otenet.gr
From: "The Name of my Bussiness"<sip:+3021098xxxxx@ims.otenet.gr:5160>;tag=as758c8c24
To: <sip:21098xxxxx@ims.otenet.gr>;tag=sbc0507sdezwcv6
CSeq: 102 INVITE
Warning: 399 S.11.311.ims.otenet.gr "SS230000F148L467[00000] Response validation failed"
Reason: Q.850;cause=41;text="temporary failure",SIP;cause=500
Content-Length: 0

<------------->
[2018-04-20 18:45:18] VERBOSE[1366] chan_sip.c: --- (9 headers 0 lines) ---
[2018-04-20 18:45:18] VERBOSE[1366][C-0000002f] chan_sip.c: Got SIP response 500 "Server Internal Error" back from 195.167.16.9:5060
[2018-04-20 18:45:18] VERBOSE[1366][C-0000002f] chan_sip.c: Transmitting (NAT) to 195.167.16.9:5060:
ACK sip:21098xxxxx@ims.otenet.gr SIP/2.0
Via: SIP/2.0/UDP 2.87.76.53:5160;branch=z9hG4bK727329a9;rport
Max-Forwards: 70
From: "The Name of my Bussiness" <sip:+3021098xxxxx@ims.otenet.gr:5160>;tag=as758c8c24
To: <sip:21098xxxxx@ims.otenet.gr>;tag=sbc0507sdezwcv6
Contact: <sip:+3021098xxxxx@2.87.76.53:5160>
Call-ID: 76c354972013f6470bba8e3873a7ac79@ims.otenet.gr
CSeq: 102 ACK
User-Agent: FPBX-14.0.2.18(13.20.0)
Content-Length: 0


---
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] app_dial.c: SIP/OTE_VoIP-00000007 is circuit-busy
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:25] NoOp("PJSIP/101-00000009", "Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 38") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:26] GotoIf("PJSIP/101-00000009", "0?continue,1:s-CONGESTION,1") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx_builtins.c: Goto (macro-dialout-trunk,s-CONGESTION,1)
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:1] Set("PJSIP/101-00000009", "RC=38") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:2] Goto("PJSIP/101-00000009", "38,1") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx_builtins.c: Goto (macro-dialout-trunk,38,1)
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [38@macro-dialout-trunk:1] Goto("PJSIP/101-00000009", "continue,1") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("PJSIP/101-00000009", "TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 38 - failing through to other trunks") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("PJSIP/101-00000009", "1?Set(CALLERID(number)=101)") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [21098xxxxx@from-internal:7] Macro("PJSIP/101-00000009", "outisbusy,") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-outisbusy:1] Progress("PJSIP/101-00000009", "") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("PJSIP/101-00000009", "0?emergency,1") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("PJSIP/101-00000009", "0?intracompany,1") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-outisbusy:4] Playback("PJSIP/101-00000009", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
[2018-04-20 18:45:18] VERBOSE[7911][C-0000002f] file.c: <PJSIP/101-00000009> Playing 'all-circuits-busy-now.ulaw' (language 'en')
[2018-04-20 18:45:19] VERBOSE[1366] chan_sip.c: Really destroying SIP dialog '76c354972013f6470bba8e3873a7ac79@ims.otenet.gr' Method: INVITE
[2018-04-20 18:45:20] VERBOSE[7911][C-0000002f] file.c: <PJSIP/101-00000009> Playing 'please-try-call-later.ulaw' (language 'en')
[2018-04-20 18:45:20] VERBOSE[7911][C-0000002f] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/101-00000009", "hangupcall") in new stack
[2018-04-20 18:45:20] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/101-00000009", "1?theend") in new stack
[2018-04-20 18:45:20] VERBOSE[7911][C-0000002f] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-04-20 18:45:20] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/101-00000009", "0?Set(CDR(recordingfile)=)") in new stack
[2018-04-20 18:45:20] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/101-00000009", " monior file= ") in new stack
[2018-04-20 18:45:20] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-hangupcall:5] AGI("PJSIP/101-00000009", "attendedtransfer-rec-restart.php,,") in new stack
[2018-04-20 18:45:20] VERBOSE[7911][C-0000002f] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-04-20 18:45:21] VERBOSE[7911][C-0000002f] res_agi.c: <PJSIP/101-00000009>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-04-20 18:45:21] VERBOSE[7911][C-0000002f] pbx.c: Executing [s@macro-hangupcall:6] Hangup("PJSIP/101-00000009", "") in new stack
[2018-04-20 18:45:21] VERBOSE[7911][C-0000002f] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'PJSIP/101-00000009' in macro 'hangupcall'
[2018-04-20 18:45:21] VERBOSE[7911][C-0000002f] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/101-00000009'
[2018-04-20 18:45:25] VERBOSE[4760] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '192.168.1.20'
[2018-04-20 18:45:25] VERBOSE[4760] netsock2.c: Using SIP RTP Audio TOS bits 184
[2018-04-20 18:45:25] VERBOSE[4760] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2018-04-20 18:45:25] VERBOSE[4760] netsock2.c: Using SIP RTP Audio CoS mark 5
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [21098xxxxx@from-internal:1] Macro("PJSIP/101-0000000a", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/101-0000000a", "TOUCH_MONITOR=1524239125.18") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/101-0000000a", "AMPUSER=101") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/101-0000000a", "0?report") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/101-0000000a", "1?Set(REALCALLERIDNUM=101)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/101-0000000a", "AMPUSER=101") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/101-0000000a", "0?limit") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/101-0000000a", "AMPUSERCIDNAME=SPA941") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("PJSIP/101-0000000a", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("PJSIP/101-0000000a", "0?report") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/101-0000000a", "AMPUSERCID=101") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/101-0000000a", "__DIAL_OPTIONS=Ttr") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/101-0000000a", "CALLERID(all)="SPA941" <101>") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/101-0000000a", "0?limit") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/101-0000000a", "1?Set(GROUP(concurrency_limit)=101)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:15] NoOp("PJSIP/101-0000000a", "Macro Depth is 1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/101-0000000a", "1?report2:macroerror") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-user-callerid,s,17)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("PJSIP/101-0000000a", "1?continue") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-user-callerid,s,35)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:35] Set("PJSIP/101-0000000a", "CALLERID(number)=101") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:36] Set("PJSIP/101-0000000a", "CALLERID(name)=SPA941") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:37] GotoIf("PJSIP/101-0000000a", "0?cnum") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:38] Set("PJSIP/101-0000000a", "CDR(cnam)=SPA941") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:39] Set("PJSIP/101-0000000a", "CDR(cnum)=101") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-user-callerid:40] Set("PJSIP/101-0000000a", "CHANNEL(language)=en") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [21098xxxxx@from-internal:2] Gosub("PJSIP/101-0000000a", "sub-record-check,s,1(out,21098xxxxx,dontcare)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/101-0000000a", "0?initialized") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/101-0000000a", "__REC_STATUS=INITIALIZED") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/101-0000000a", "NOW=1524239125") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/101-0000000a", "__DAY=20") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/101-0000000a", "__MONTH=04") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/101-0000000a", "__YEAR=2018") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/101-0000000a", "__TIMESTR=20180420-184525") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/101-0000000a", "__FROMEXTEN=101") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/101-0000000a", "__MON_FMT=wav") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/101-0000000a", "Recordings initialized") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/101-0000000a", "0?Set(ARG3=dontcare)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/101-0000000a", "REC_POLICY_MODE_SAVE=") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/101-0000000a", "0?Set(REC_STATUS=NO)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/101-0000000a", "3?checkaction") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/101-0000000a", "1?sub-record-check,out,1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/101-0000000a", "Outbound Recording Check from 101 to 21098xxxxx") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/101-0000000a", "RECMODE=dontcare") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/101-0000000a", "1?Goto(routewins)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/101-0000000a", "recordcheck,1(dontcare,out,21098xxxxx)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/101-0000000a", "Starting recording check against dontcare") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/101-0000000a", "dontcare") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/101-0000000a", "") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/101-0000000a", "") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [21098xxxxx@from-internal:3] Set("PJSIP/101-0000000a", "MOHCLASS=default") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [21098xxxxx@from-internal:4] ExecIf("PJSIP/101-0000000a", "1?Set(TRUNKCIDOVERRIDE="The Name of my Bussiness" <21098xxxxx>)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [21098xxxxx@from-internal:5] Set("PJSIP/101-0000000a", "_NODEST=") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [21098xxxxx@from-internal:6] Macro("PJSIP/101-0000000a", "dialout-trunk,2,21098xxxxx,,off") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/101-0000000a", "DIAL_TRUNK=2") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf("PJSIP/101-0000000a", "0?sub-pincheck,s,1()") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf("PJSIP/101-0000000a", "0?Set(CALLERID(num)=101)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:4] GotoIf("PJSIP/101-0000000a", "0?disabletrunk,1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:5] Set("PJSIP/101-0000000a", "DIAL_NUMBER=21098xxxxx") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/101-0000000a", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/101-0000000a", "OUTBOUND_GROUP=OUT_2") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/101-0000000a", "DIAL_TRUNK_OPTIONS=T") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("PJSIP/101-0000000a", "1?nomax") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-dialout-trunk,s,11)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("PJSIP/101-0000000a", "0?skipoutcid") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:12] Macro("PJSIP/101-0000000a", "outbound-callerid,2") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/101-0000000a", "101") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/101-0000000a", "") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/101-0000000a", "off") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/101-0000000a", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/101-0000000a", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf("PJSIP/101-0000000a", "0?Set(REALCALLERIDNUM=101)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:7] GotoIf("PJSIP/101-0000000a", "1?normcid") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-outbound-callerid,s,11)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/101-0000000a", "USEROUTCID=") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:12] Set("PJSIP/101-0000000a", "EMERGENCYCID=") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:13] Set("PJSIP/101-0000000a", "TRUNKOUTCID=+3021098xxxxx") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:14] GotoIf("PJSIP/101-0000000a", "1?trunkcid") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-outbound-callerid,s,19)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf("PJSIP/101-0000000a", "1?Set(CALLERID(all)=+3021098xxxxx)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:20] ExecIf("PJSIP/101-0000000a", "0?Set(CALLERID(all)=)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf("PJSIP/101-0000000a", "1?Set(CALLERID(all)=The Name of my Bussiness <21098xxxxx>)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/101-0000000a", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf("PJSIP/101-0000000a", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:24] Set("PJSIP/101-0000000a", "CDR(outbound_cnum)=21098xxxxx") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outbound-callerid:25] Set("PJSIP/101-0000000a", "CDR(outbound_cnam)=The Name of my Bussiness") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:13] GosubIf("PJSIP/101-0000000a", "0?sub-flp-2,s,1()") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:14] Set("PJSIP/101-0000000a", "OUTNUM=21098xxxxx") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:15] Set("PJSIP/101-0000000a", "custom=SIP/OTE_VoIP") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("PJSIP/101-0000000a", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/101-0000000a", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:18] Macro("PJSIP/101-0000000a", "dialout-trunk-predial-hook,") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/101-0000000a", "") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:19] GotoIf("PJSIP/101-0000000a", "0?bypass,1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/101-0000000a", "1?Set(CONNECTEDLINE(num,i)=21098xxxxx)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/101-0000000a", "1?Set(CONNECTEDLINE(name,i)=CID:21098xxxxx)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/101-0000000a", "0?Set(CONNECTEDLINE(name,i)=CIDHidden)21098xxxxx)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:23] GotoIf("PJSIP/101-0000000a", "0?customtrunk") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:24] Dial("PJSIP/101-0000000a", "SIP/OTE_VoIP/21098xxxxx,300,Tb(func-apply-sipheaders^s^1)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] netsock2.c: Using SIP RTP TOS bits 184
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] netsock2.c: Using SIP RTP CoS mark 5
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] app_stack.c: SIP/OTE_VoIP-00000008 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/OTE_VoIP-00000008", "Applying SIP Headers to channel") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/OTE_VoIP-00000008", "SIPHEADERKEYS=") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/OTE_VoIP-00000008", "0") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] app_while.c: Jumping to priority 7
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/OTE_VoIP-00000008", "") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] app_stack.c: Spawn extension (from-trunk-sip-OTE_VoIP, 21098xxxxx, 1) exited non-zero on 'SIP/OTE_VoIP-00000008'
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] app_stack.c: SIP/OTE_VoIP-00000008 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] chan_sip.c: Audio is at 10796
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] chan_sip.c: Adding codec ulaw to SDP
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] chan_sip.c: Adding codec alaw to SDP
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] chan_sip.c: Adding codec gsm to SDP
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] chan_sip.c: Adding codec g726 to SDP
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] chan_sip.c: Adding codec g722 to SDP
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] chan_sip.c: Reliably Transmitting (NAT) to 195.167.16.9:5060:
INVITE sip:21098xxxxx@ims.otenet.gr SIP/2.0
Via: SIP/2.0/UDP 2.87.76.53:5160;branch=z9hG4bK18ce82fe;rport
Max-Forwards: 70
From: "The Name of my Bussiness" <sip:+3021098xxxxx@ims.otenet.gr:5160>;tag=as0351c4db
To: <sip:21098xxxxx@ims.otenet.gr>
Contact: <sip:+3021098xxxxx@2.87.76.53:5160>
Call-ID: 5adc72525c010d9b328c338e303e9be1@ims.otenet.gr
CSeq: 102 INVITE
User-Agent: FPBX-14.0.2.18(13.20.0)
Date: Fri, 20 Apr 2018 15:45:25 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 350

v=0
o=root 1890958923 1890958923 IN IP4 2.87.76.53
s=Asterisk PBX 13.20.0
c=IN IP4 2.87.76.53
t=0 0
m=audio 10796 RTP/AVP 0 8 3 111 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

---
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] app_dial.c: Called SIP/OTE_VoIP/21098xxxxx
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:195.167.16.9:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 2.87.76.53:5160;branch=z9hG4bK18ce82fe;rport=5160
Call-ID: 5adc72525c010d9b328c338e303e9be1@ims.otenet.gr
From: "The Name of my Bussiness"<sip:+3021098xxxxx@ims.otenet.gr:5160>;tag=as0351c4db
To: <sip:21098xxxxx@ims.otenet.gr>
CSeq: 102 INVITE
Content-Length: 0

<------------->
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c: --- (7 headers 0 lines) ---
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:195.167.16.9:5060 --->
INVITE sip:+3021098xxxxx@2.87.76.53:5160 SIP/2.0
Via: SIP/2.0/UDP 195.167.16.9:5060;branch=z9hG4bK9350d5ed0dzx85hz5047h7h87T10406
Call-ID: asbcd6dx61sexvdswfu6e0zvyfv1zcfun0dn....ims.otenet.gr.79
From: <sip:21098xxxxx@ims.otenet.gr;user=phone>;tag=sbc0506dfvsvuy6-CC-79
To: <sip:21098xxxxx@ims.otenet.gr>
CSeq: 1 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY,INFO,PUBLISH,MESSAGE
Contact: <sip:+3021098xxxxx@195.167.16.9:5060>
Max-Forwards: 64
P-Asserted-Identity: <sip:+3021098xxxxx@ims.otenet.gr>,<tel:+3021098xxxxx>
P-Called-Party-ID: <tel:+3021098xxxxx>
Content-Length: 335
Content-Type: application/sdp

v=0
o=- 46865851 46865851 IN IP4 195.167.17.9
s=SBC call
c=IN IP4 195.167.17.9
t=0 0
m=audio 10804 RTP/AVP 0 8 3 111 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
<------------->
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c: --- (13 headers 16 lines) ---
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c: Sending to 195.167.16.9:5060 (NAT)
[2018-04-20 18:45:25] VERBOSE[1366][C-00000032] chan_sip.c: Sending to 195.167.16.9:5060 (NAT)
[2018-04-20 18:45:25] VERBOSE[1366][C-00000032] chan_sip.c: Using INVITE request as basis request - asbcd6dx61sexvdswfu6e0zvyfv1zcfun0dn....ims.otenet.gr.79
[2018-04-20 18:45:25] VERBOSE[1366][C-00000032] chan_sip.c: Found peer '+3021098xxxxx@ims.otenet.gr' for '21098xxxxx' from 195.167.16.9:5060
[2018-04-20 18:45:25] VERBOSE[1366][C-00000032] chan_sip.c:
<--- Reliably Transmitting (NAT) to 195.167.16.9:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 195.167.16.9:5060;branch=z9hG4bK9350d5ed0dzx85hz5047h7h87T10406;received=195.167.16.9;rport=5060
From: <sip:21098xxxxx@ims.otenet.gr;user=phone>;tag=sbc0506dfvsvuy6-CC-79
To: <sip:21098xxxxx@ims.otenet.gr>;tag=as3fbac7df
Call-ID: asbcd6dx61sexvdswfu6e0zvyfv1zcfun0dn....ims.otenet.gr.79
CSeq: 1 INVITE
Server: FPBX-14.0.2.18(13.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="6faa7851"
Content-Length: 0


<------------>
[2018-04-20 18:45:25] VERBOSE[1366][C-00000032] chan_sip.c: Scheduling destruction of SIP dialog 'asbcd6dx61sexvdswfu6e0zvyfv1zcfun0dn@ATS.athe-ATS1.ims.otenet.gr.79' in 32000 ms (Method: INVITE)
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:195.167.16.9:5060 --->
ACK sip:+3021098xxxxx@2.87.76.53:5160 SIP/2.0
Via: SIP/2.0/UDP 195.167.16.9:5060;branch=z9hG4bK9350d5ed0dzx85hz5047h7h87T10406;received=195.167.16.9;rport=5060
Call-ID: asbcd6dx61sexvdswfu6e0zvyfv1zcfun0dn....ims.otenet.gr.79
From: <sip:21098xxxxx@ims.otenet.gr;user=phone>;tag=sbc0506dfvsvuy6-CC-79
To: <sip:21098xxxxx@ims.otenet.gr>;tag=as3fbac7df
CSeq: 1 ACK
Max-Forwards: 70
Content-Length: 0

<------------->
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c: --- (8 headers 0 lines) ---
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:195.167.16.9:5060 --->
SIP/2.0 500 Server Internal Error
Via: SIP/2.0/UDP 2.87.76.53:5160;branch=z9hG4bK18ce82fe;rport=5160
Call-ID: 5adc72525c010d9b328c338e303e9be1@ims.otenet.gr
From: "The Name of my Bussiness"<sip:+3021098xxxxx@ims.otenet.gr:5160>;tag=as0351c4db
To: <sip:21098xxxxx@ims.otenet.gr>;tag=sbc05051dnywskz
CSeq: 102 INVITE
Warning: 399 S.11.311.ims.otenet.gr "SS230000F148L467[00000] Response validation failed"
Reason: Q.850;cause=41;text="temporary failure",SIP;cause=500
Content-Length: 0

<------------->
[2018-04-20 18:45:25] VERBOSE[1366] chan_sip.c: --- (9 headers 0 lines) ---
[2018-04-20 18:45:25] VERBOSE[1366][C-00000031] chan_sip.c: Got SIP response 500 "Server Internal Error" back from 195.167.16.9:5060
[2018-04-20 18:45:25] VERBOSE[1366][C-00000031] chan_sip.c: Transmitting (NAT) to 195.167.16.9:5060:
ACK sip:21098xxxxx@ims.otenet.gr SIP/2.0
Via: SIP/2.0/UDP 2.87.76.53:5160;branch=z9hG4bK18ce82fe;rport
Max-Forwards: 70
From: "The Name of my Bussiness" <sip:+3021098xxxxx@ims.otenet.gr:5160>;tag=as0351c4db
To: <sip:21098xxxxx@ims.otenet.gr>;tag=sbc05051dnywskz
Contact: <sip:+3021098xxxxx@2.87.76.53:5160>
Call-ID: 5adc72525c010d9b328c338e303e9be1@ims.otenet.gr
CSeq: 102 ACK
User-Agent: FPBX-14.0.2.18(13.20.0)
Content-Length: 0


---
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] app_dial.c: SIP/OTE_VoIP-00000008 is circuit-busy
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:25] NoOp("PJSIP/101-0000000a", "Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 38") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-dialout-trunk:26] GotoIf("PJSIP/101-0000000a", "0?continue,1:s-CONGESTION,1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-dialout-trunk,s-CONGESTION,1)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:1] Set("PJSIP/101-0000000a", "RC=38") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:2] Goto("PJSIP/101-0000000a", "38,1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-dialout-trunk,38,1)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [38@macro-dialout-trunk:1] Goto("PJSIP/101-0000000a", "continue,1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("PJSIP/101-0000000a", "TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 38 - failing through to other trunks") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("PJSIP/101-0000000a", "1?Set(CALLERID(number)=101)") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [21098xxxxx@from-internal:7] Macro("PJSIP/101-0000000a", "outisbusy,") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outisbusy:1] Progress("PJSIP/101-0000000a", "") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("PJSIP/101-0000000a", "0?emergency,1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("PJSIP/101-0000000a", "0?intracompany,1") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-outisbusy:4] Playback("PJSIP/101-0000000a", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
[2018-04-20 18:45:25] VERBOSE[7939][C-00000031] file.c: <PJSIP/101-0000000a> Playing 'all-circuits-busy-now.ulaw' (language 'en')
[2018-04-20 18:45:26] VERBOSE[1366] chan_sip.c: Really destroying SIP dialog '5adc72525c010d9b328c338e303e9be1@ims.otenet.gr' Method: INVITE
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] file.c: <PJSIP/101-0000000a> Playing 'please-try-call-later.ulaw' (language 'en')
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/101-0000000a", "hangupcall") in new stack
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/101-0000000a", "1?theend") in new stack
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/101-0000000a", "0?Set(CDR(recordingfile)=)") in new stack
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/101-0000000a", " monior file= ") in new stack
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-hangupcall:5] AGI("PJSIP/101-0000000a", "attendedtransfer-rec-restart.php,,") in new stack
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] res_agi.c: <PJSIP/101-0000000a>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] pbx.c: Executing [s@macro-hangupcall:6] Hangup("PJSIP/101-0000000a", "") in new stack
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'PJSIP/101-0000000a' in macro 'hangupcall'
[2018-04-20 18:45:27] VERBOSE[7939][C-00000031] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/101-0000000a'
FreePBX® FreePBX is a registered trademark of
Sangoma Technologies Inc.
FreePBX 14.0.2.18 is licensed under the GPL
Copyright© 2007-2018www.sangoma.com

[astbox]

Έχεις αλλάξει την bind port στον asterisk; Επίσης μπορείς να κάνεις ένα ip addr στο ras και ανεβάσεις τι σου βγάζει.

[deniSun]

S P O I L E R

[KostasGe]

S P O I L E R

??????

- - - Updated - - -

Έχεις αλλάξει την bind port στον asterisk; Επίσης μπορείς να κάνεις ένα ip addr στο ras και ανεβάσεις τι σου βγάζει.

Όχι.
Εννοείς τι ip έχει το pi;

[astbox]

Όχι την ip, τι σου βγάζει το ip addr. Χρησιμοποιείς sip trunk αλλά για κάποιο λόγο βγαίνει με port την 5160 που είναι η πόρτα του pjsip.
Το trunk που έχεις φτιάξει είναι sip ή pjsip; Τα εσωτερικά σου τι είναι sip ή pjsip;

[KostasGe]

Όχι την ip, τι σου βγάζει το ip addr. Χρησιμοποιείς sip trunk αλλά για κάποιο λόγο βγαίνει με port την 5160 που είναι η πόρτα του pjsip.
Το trunk που έχεις φτιάξει είναι sip ή pjsip; Τα εσωτερικά σου τι είναι sip ή pjsip;

To Trunk είναι sip και τα εσωτερικά pjsip