-- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/99-000001ec", "0?customtrunk") in new stack -- Executing [s@macro-dialout-trunk:22] Dial("SIP/99-000001ec", "DAHDI/g1/343777469,300,") in new stack PRI Span: 2 -- Making new call for cref 32772 -- Requested transfer capability: 0x00 - SPEECH PRI Span: 2 PRI Span: 2 > DL-DATA request PRI Span: 2 > Protocol Discriminator: Q.931 (8) len=38 PRI Span: 2 > TEI=-66 Call Ref: len= 1 (reference 4/0x4) (Sent from originator) PRI Span: 2 > Message Type: SETUP (5) PRI Span: 2 Changing from state 1(TEI unassigned) to 3(Establish awaiting TEI) PRI Span: 2 Sending TEI management message 1(TEI Identity Request), TEI=127 PRI Span: 2 TEI=-66 Just queued I-frame since in state 3(Establish awaiting TEI) PRI Span: 2 q931.c:6219 q931_setup: Call 32772 enters state 1 (Call Initiated). Hold state: Idle -- Called DAHDI/g1/343777469 PRI Span: 2 T303 timed out. cref:32772 PRI Span: 2 PRI Span: 2 > DL-DATA request PRI Span: 2 > Protocol Discriminator: Q.931 (8) len=38 PRI Span: 2 > TEI=-66 Call Ref: len= 1 (reference 4/0x4) (Sent from originator) PRI Span: 2 > Message Type: SETUP (5) PRI Span: 2 TEI=-66 Just queued I-frame since in state 3(Establish awaiting TEI) PRI Span: 2 T303 timed out. cref:32772 PRI Span: 2 q931.c:6108 t303_expiry: Call 32772 enters state 0 (Null). Hold state: Idle PRI Span: 2 Fake clearing. cref:32772 PRI Span: 2 q931.c:9469 pri_internal_clear: alive 1, hangupack 1 Span 2: Processing event PRI_EVENT_HANGUP(6) -- Span 2: Channel 0/1 got hangup, cause 18 PRI Span: 2 q931.c:7020 q931_hangup: Hangup other cref:32772 PRI Span: 2 q931.c:6777 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle PRI Span: 2 Destroying call 0x8eb88c8, ourstate Null, peerstate Null, hold-state Idle -- Hungup 'DAHDI/i2/343777469-5' == Everyone is busy/congested at this time (1:0/0/1) -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/99-000001ec", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 18") in new stack -- Executing [s@macro-dialout-trunk:24] Goto("SIP/99-000001ec", "s-CHANUNAVAIL,1") in new stack -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1) -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/99-000001ec", "RC=18") in new stack -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/99-000001ec", "18,1") in new stack -- Goto (macro-dialout-trunk,18,1) -- Executing [18@macro-dialout-trunk:1] Goto("SIP/99-000001ec", "s-NOANSWER,1") in new stack -- Goto (macro-dialout-trunk,s-NOANSWER,1) -- Executing [s-NOANSWER@macro-dialout-trunk:1] NoOp("SIP/99-000001ec", "Dial failed due to trunk reporting NOANSWER - giving up") in new stack -- Executing [s-NOANSWER@macro-dialout-trunk:2] Progress("SIP/99-000001ec", "") in new stack -- Executing [s-NOANSWER@macro-dialout-trunk:3] Playback("SIP/99-000001ec", "number-not-answering,noanswer") in new stack -- Playing 'number-not-answering.ulaw' (language 'en') -- Executing [s-NOANSWER@macro-dialout-trunk:4] Congestion("SIP/99-000001ec", "20") in new stack [2013-02-13 22:48:09] WARNING[29241][C-00000175]: channel.c:4810 ast_prod: Prodding channel 'SIP/99-000001ec' failed == Spawn extension (macro-dialout-trunk, s-NOANSWER, 4) exited non-zero on 'SIP/99-000001ec' in macro 'dialout-trunk' == Spawn extension (from-internal, 343777469, 5) exited non-zero on 'SIP/99-000001ec' -- Executing [h@from-internal:1] Hangup("SIP/99-000001ec", "") in new stack == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/99-000001ec' == MixMonitor close filestream (mixed) == End MixMonitor Recording SIP/99-000001ec PRI Span: 2 Sending TEI management message 1(TEI Identity Request), TEI=127 PRI Span: 2 Sending TEI management message 1(TEI Identity Request), TEI=127 [2013-02-13 22:48:29] ERROR[29114]: chan_dahdi.c:14449 dahdi_pri_error: PRI Span: 2 Unable to receive TEI from network in state 3(Establish awaiting TEI)! PRI Span: 2 TEI=-66 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3) PRI Span: 2 Changing from state 3(Establish awaiting TEI) to 1(TEI unassigned) asterisk*CLI> ########### dahdi restart below, and it's working again ########### asterisk*CLI> dahdi restart Destroying channels and reloading DAHDI configuration. -- Unregistered channel -2 -- Unregistered channel 1 -- Unregistered channel 2 -- Unregistered channel 3 -- Unregistered channel 4 -- Unregistered channel 5 -- Unregistered channel 6 -- Unregistered channel 8 -- Unregistered channel 9 == Parsing '/etc/asterisk/chan_dahdi.conf': Found == Parsing '/etc/asterisk/chan_dahdi_general.conf': Found == Parsing '/etc/asterisk/dahdi-channels.conf': Found == Parsing '/etc/asterisk/chan_dahdi_groups.conf': Found == Parsing '/etc/asterisk/chan_dahdi_additional.conf': Found == Parsing '/etc/asterisk/users.conf': Found -- Reconfigured channel 1, FXS Kewlstart signalling -- Reconfigured channel 2, FXS Kewlstart signalling [2013-02-13 22:51:01] WARNING[29134]: chan_dahdi.c:8021 handle_alarms: Detected alarm on channel 3: Red Alarm -- Reconfigured channel 3, FXS Kewlstart signalling [2013-02-13 22:51:01] WARNING[29134]: chan_dahdi.c:8021 handle_alarms: Detected alarm on channel 4: Red Alarm -- Reconfigured channel 4, FXS Kewlstart signalling -- Reconfigured channel 5, ISDN BRI Point to MultiPoint signalling -- Reconfigured channel 6, ISDN BRI Point to MultiPoint signalling -- Reconfigured channel 8, ISDN BRI Point to MultiPoint signalling -- Reconfigured channel 9, ISDN BRI Point to MultiPoint signalling -- Automatically generated pseudo channel [2013-02-13 22:51:01] WARNING[29134]: chan_dahdi.c:18551 process_dahdi: Ignoring any changes to 'userbase' (on reload) at line 23. [2013-02-13 22:51:01] WARNING[29134]: chan_dahdi.c:18551 process_dahdi: Ignoring any changes to 'vmsecret' (on reload) at line 31. [2013-02-13 22:51:01] WARNING[29134]: chan_dahdi.c:18551 process_dahdi: Ignoring any changes to 'hassip' (on reload) at line 35. [2013-02-13 22:51:01] WARNING[29134]: chan_dahdi.c:18551 process_dahdi: Ignoring any changes to 'hasiax' (on reload) at line 39. [2013-02-13 22:51:01] WARNING[29134]: chan_dahdi.c:18551 process_dahdi: Ignoring any changes to 'hasmanager' (on reload) at line 47. == Starting D-Channel on span 2 == Starting D-Channel on span 3 == Primary D-Channel on span 3 up == Primary D-Channel on span 2 up == Using SIP VIDEO TOS bits 136 == Using SIP VIDEO CoS mark 6 == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 -- Executing [234234234@from-internal:1] Macro("SIP/99-000001ed", "user-callerid,LIMIT,") in new stack -- Executing [s@macro-user-callerid:1] Set("SIP/99-000001ed", "AMPUSER=99") in new stack -- Executing [s@macro-user-callerid:2] GotoIf("SIP/99-000001ed", "0?report") in new stack -- Executing [s@macro-user-callerid:3] ExecIf("SIP/99-000001ed", "1?Set(REALCALLERIDNUM=99)") in new stack -- Executing [s@macro-user-callerid:4] Set("SIP/99-000001ed", "AMPUSER=99") in new stack -- Executing [s@macro-user-callerid:5] Set("SIP/99-000001ed", "AMPUSERCIDNAME=android 99") in new stack -- Executing [s@macro-user-callerid:6] GotoIf("SIP/99-000001ed", "0?report") in new stack -- Executing [s@macro-user-callerid:7] Set("SIP/99-000001ed", "AMPUSERCID=99") in new stack -- Executing [s@macro-user-callerid:8] Set("SIP/99-000001ed", "CALLERID(all)="android 99" <99>") in new stack -- Executing [s@macro-user-callerid:9] GotoIf("SIP/99-000001ed", "0?limit") in new stack -- Executing [s@macro-user-callerid:10] ExecIf("SIP/99-000001ed", "1?Set(GROUP(concurrency_limit)=99)") in new stack -- Executing [s@macro-user-callerid:11] ExecIf("SIP/99-000001ed", "0?Set(CHANNEL(language)=)") in new stack -- Executing [s@macro-user-callerid:12] GosubIf("SIP/99-000001ed", "7?sub-ccss,s,1(from-internal,234234234)") in new stack -- Executing [s@sub-ccss:1] ExecIf("SIP/99-000001ed", "0?Return()") in new stack -- Executing [s@sub-ccss:2] Set("SIP/99-000001ed", "CCSS_SETUP=TRUE") in new stack -- Executing [s@sub-ccss:3] GosubIf("SIP/99-000001ed", "0?monitor_config,1(from-internal,234234234):monitor_default,1(from-internal,234234234)") in new stack -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/99-000001ed", "0?is_exten") in new stack -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/99-000001ed", "") in new stack -- Executing [monitor_default@sub-ccss:3] Return("SIP/99-000001ed", "FALSE") in new stack -- Executing [s@macro-user-callerid:13] GotoIf("SIP/99-000001ed", "1?continue") in new stack -- Goto (macro-user-callerid,s,26) -- Executing [s@macro-user-callerid:26] Set("SIP/99-000001ed", "CALLERID(number)=99") in new stack -- Executing [s@macro-user-callerid:27] Set("SIP/99-000001ed", "CALLERID(name)=android 99") in new stack -- Executing [s@macro-user-callerid:28] Set("SIP/99-000001ed", "CHANNEL(language)=en") in new stack -- Executing [234234234@from-internal:2] Set("SIP/99-000001ed", "MOHCLASS=default") in new stack -- Executing [234234234@from-internal:3] Set("SIP/99-000001ed", "_NODEST=") in new stack -- Executing [234234234@from-internal:4] Gosub("SIP/99-000001ed", "sub-record-check,s,1(out,234234234,)") in new stack -- Executing [s@sub-record-check:1] GotoIf("SIP/99-000001ed", "1?check") in new stack -- Goto (sub-record-check,s,6) -- Executing [s@sub-record-check:6] Set("SIP/99-000001ed", "__MON_FMT=gsm") in new stack -- Executing [s@sub-record-check:7] GotoIf("SIP/99-000001ed", "1?next") in new stack -- Goto (sub-record-check,s,10) -- Executing [s@sub-record-check:10] ExecIf("SIP/99-000001ed", "0?Return()") in new stack -- Executing [s@sub-record-check:11] GotoIf("SIP/99-000001ed", "0?out,1") in new stack -- Executing [s@sub-record-check:12] Set("SIP/99-000001ed", "__REC_STATUS=INITIALIZED") in new stack -- Executing [s@sub-record-check:13] ExecIf("SIP/99-000001ed", "0?Set(__REC_POLICY_MODE=)") in new stack -- Executing [s@sub-record-check:14] Set("SIP/99-000001ed", "NOW=1360795883") in new stack -- Executing [s@sub-record-check:15] Set("SIP/99-000001ed", "__DAY=13") in new stack -- Executing [s@sub-record-check:16] Set("SIP/99-000001ed", "__MONTH=02") in new stack -- Executing [s@sub-record-check:17] Set("SIP/99-000001ed", "__YEAR=2013") in new stack -- Executing [s@sub-record-check:18] Set("SIP/99-000001ed", "__TIMESTR=20130213-225123") in new stack -- Executing [s@sub-record-check:19] Set("SIP/99-000001ed", "__FROMEXTEN=99") in new stack -- Executing [s@sub-record-check:20] Set("SIP/99-000001ed", "__CALLFILENAME=out-234234234-99-20130213-225123-1360795883.719") in new stack -- Executing [s@sub-record-check:21] Goto("SIP/99-000001ed", "out,1") in new stack -- Goto (sub-record-check,out,1) -- Executing [out@sub-record-check:1] ExecIf("SIP/99-000001ed", "1?Set(__REC_POLICY_MODE=always)") in new stack -- Executing [out@sub-record-check:2] GosubIf("SIP/99-000001ed", "1?record,1(exten,234234234,99)") in new stack -- Executing [record@sub-record-check:1] Set("SIP/99-000001ed", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack -- Executing [record@sub-record-check:2] MixMonitor("SIP/99-000001ed", "2013/02/13/out-234234234-99-20130213-225123-1360795883.719.gsm,,") in new stack -- Executing [record@sub-record-check:3] Set("SIP/99-000001ed", "__REC_STATUS=RECORDING") in new stack -- Executing [record@sub-record-check:4] Set("SIP/99-000001ed", "CDR(recordingfile)=out-234234234-99-20130213-225123-1360795883.719.gsm") in new stack -- Executing [record@sub-record-check:5] Return("SIP/99-000001ed", "") in new stack -- Executing [out@sub-record-check:3] Return("SIP/99-000001ed", "") in new stack -- Executing [234234234@from-internal:5] Macro("SIP/99-000001ed", "dialout-trunk,2,234234234,") in new stack -- Executing [s@macro-dialout-trunk:1] Set("SIP/99-000001ed", "DIAL_TRUNK=2") in new stack -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/99-000001ed", "0?sub-pincheck,s,1()") in new stack -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/99-000001ed", "0?disabletrunk,1") in new stack -- Executing [s@macro-dialout-trunk:4] Set("SIP/99-000001ed", "DIAL_NUMBER=234234234") in new stack -- Executing [s@macro-dialout-trunk:5] Set("SIP/99-000001ed", "DIAL_TRUNK_OPTIONS=tr") in new stack -- Executing [s@macro-dialout-trunk:6] Set("SIP/99-000001ed", "OUTBOUND_GROUP=OUT_2") in new stack -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/99-000001ed", "1?nomax") in new stack -- Goto (macro-dialout-trunk,s,9) == Begin MixMonitor Recording SIP/99-000001ed -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/99-000001ed", "0?skipoutcid") in new stack -- Executing [s@macro-dialout-trunk:10] Set("SIP/99-000001ed", "DIAL_TRUNK_OPTIONS=") in new stack -- Executing [s@macro-dialout-trunk:11] Macro("SIP/99-000001ed", "outbound-callerid,2") in new stack -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/99-000001ed", "0?Set(CALLERPRES()=)") in new stack -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/99-000001ed", "0?Set(REALCALLERIDNUM=99)") in new stack -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/99-000001ed", "1?normcid") in new stack -- Goto (macro-outbound-callerid,s,6) -- Executing [s@macro-outbound-callerid:6] Set("SIP/99-000001ed", "USEROUTCID=") in new stack -- Executing [s@macro-outbound-callerid:7] Set("SIP/99-000001ed", "EMERGENCYCID=") in new stack -- Executing [s@macro-outbound-callerid:8] Set("SIP/99-000001ed", "TRUNKOUTCID=234234234") in new stack -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/99-000001ed", "1?trunkcid") in new stack -- Goto (macro-outbound-callerid,s,12) -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/99-000001ed", "1?Set(CALLERID(all)=234234234)") in new stack -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/99-000001ed", "0?Set(CALLERID(all)=)") in new stack -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/99-000001ed", "0?Set(CALLERID(all)=)") in new stack -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/99-000001ed", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/99-000001ed", "0?sub-flp-2,s,1()") in new stack -- Executing [s@macro-dialout-trunk:13] Set("SIP/99-000001ed", "OUTNUM=234234234") in new stack -- Executing [s@macro-dialout-trunk:14] Set("SIP/99-000001ed", "custom=DAHDI/g1") in new stack -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/99-000001ed", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))") in new stack -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/99-000001ed", "0?Set(DIAL_TRUNK_OPTIONS=M(confirm))") in new stack -- Executing [s@macro-dialout-trunk:17] Macro("SIP/99-000001ed", "dialout-trunk-predial-hook,") in new stack -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/99-000001ed", "") in new stack -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/99-000001ed", "0?bypass,1") in new stack -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/99-000001ed", "1?Set(CONNECTEDLINE(num,i)=234234234)") in new stack -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/99-000001ed", "1?Set(CONNECTEDLINE(name,i)=CID:234234234)") in new stack -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/99-000001ed", "0?customtrunk") in new stack -- Executing [s@macro-dialout-trunk:22] Dial("SIP/99-000001ed", "DAHDI/g1/234234234,300,") in new stack -- Requested transfer capability: 0x00 - SPEECH -- Called DAHDI/g1/234234234 -- DAHDI/i2/234234234-1 is proceeding passing it to SIP/99-000001ed -- Accepting call from '234234234' to '234234234' on channel 0/2, span 3