[May 27 09:52:56] Asterisk 11.14.1 built by mockbuild @ mock1.cfware.com on a x86_64 running Linux on 2014-11-21 04:10:03 UTC [May 27 10:02:06] DEBUG[12159] manager.c: Running action 'Originate' [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newchannel Privilege: call,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 ChannelState: 0 ChannelStateDesc: Down CallerIDNum: CallerIDName: AccountCode: Exten: 9179629695 Context: cfware_robodialer_entry Uniqueid: 1432735326.0 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newchannel Privilege: call,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 ChannelState: 4 ChannelStateDesc: Ring CallerIDNum: CallerIDName: AccountCode: Exten: 9179629695 Context: cfware_robodialer_entry Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 Variable: ROBODIALER_PROJECTID Value: 42 Uniqueid: 1432735326.0 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 Variable: ROBODIALER_CALLERID Value: 5184554100 Uniqueid: 1432735326.0 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 Variable: ROBODIALER_TIMEOUT Value: 30 Uniqueid: 1432735326.0 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 Variable: ROBODIALER_PHONE Value: 9179629695 Uniqueid: 1432735326.0 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 Variable: ROBODIALER_IVR Value: 23 Uniqueid: 1432735326.0 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: NewAccountCode Privilege: call,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 Uniqueid: 1432735326.0 AccountCode: OldAccountCode: [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: NewCallerid Privilege: call,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 CallerIDNum: CallerIDName: Uniqueid: 1432735326.0 CID-CallingPres: 67 (Number Unavailable) [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: LocalBridge Privilege: call,all Channel1: Local/9179629695@cfware_robodialer_entry-00000000;1 Channel2: Local/9179629695@cfware_robodialer_entry-00000000;2 Uniqueid1: 1432735326.0 Uniqueid2: 1432735326.1 Context: cfware_robodialer_entry Exten: 9179629695 LocalOptimization: Yes [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Result of 'ROBODIALER_CALLERID' is '5184554100' [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Launching 'Set' [May 27 10:02:06] VERBOSE[12314][C-00000000] pbx.c: -- Executing [9179629695@cfware_robodialer_entry:1] Set("Local/9179629695@cfware_robodialer_entry-00000000;2", "CALLERID(num)=5184554100") in new stack [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newexten Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Context: cfware_robodialer_entry Extension: 9179629695 Priority: 1 Application: Set AppData: CALLERID(num)=5184554100 Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: NewCallerid Privilege: call,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 CallerIDNum: 5184554100 CallerIDName: Uniqueid: 1432735326.1 CID-CallingPres: 0 (Presentation Allowed, Not Screened) [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Result of 'DIALER_INTERFACE' is 'DAHDI/g0' [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Launching 'ChanIsAvail' [May 27 10:02:06] VERBOSE[12314][C-00000000] pbx.c: -- Executing [9179629695@cfware_robodialer_entry:2] ChanIsAvail("Local/9179629695@cfware_robodialer_entry-00000000;2", "DAHDI/g0") in new stack [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newexten Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Context: cfware_robodialer_entry Extension: 9179629695 Priority: 2 Application: ChanIsAvail AppData: DAHDI/g0 Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12314][C-00000000] chan_dahdi.c: Using channel 1 [May 27 10:02:06] DEBUG[12314][C-00000000] sig_pri.c: sig_pri_request 1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newchannel Privilege: call,all Channel: DAHDI/i1/-1 ChannelState: 1 ChannelStateDesc: Rsrvd CallerIDNum: CallerIDName: AccountCode: Exten: Context: from-pstn Uniqueid: 1432735326.2 [May 27 10:02:06] DEBUG[12314][C-00000000] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [May 27 10:02:06] DEBUG[12314][C-00000000] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [May 27 10:02:06] DEBUG[12152] devicestate.c: No provider found, checking channel drivers for DAHDI - i1/ [May 27 10:02:06] DEBUG[12152] devicestate.c: Changing state for DAHDI/i1/ - state 2 (In use) [May 27 10:02:06] DEBUG[12152] devicestate.c: device 'DAHDI/i1/' state '2' [May 27 10:02:06] DEBUG[12314][C-00000000] channel_internal_api.c: Channel Call ID changing from [C-00000000] to [C-00000000] [May 27 10:02:06] DEBUG[12314][C-00000000] channel.c: Hanging up channel 'DAHDI/i1/-1' [May 27 10:02:06] DEBUG[12314][C-00000000] chan_dahdi.c: dahdi_hangup(DAHDI/i1/-1) [May 27 10:02:06] DEBUG[12190] app_queue.c: Device 'DAHDI/i1/' changed to state '2' (In use) but we don't care because they're not a member of any queue. [May 27 10:02:06] DEBUG[12314][C-00000000] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/i1/-1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: DAHDIChannel Privilege: call,all Channel: DAHDI/i1/-1 Uniqueid: 1432735326.2 DAHDISpan: 1 DAHDIChannel: 1 [May 27 10:02:06] DEBUG[12314][C-00000000] sig_pri.c: sig_pri_hangup 1 [May 27 10:02:06] DEBUG[12314][C-00000000] sig_pri.c: Channel 'DAHDI/i1/-1' MOH-Event: SIG_PRI_MOH_EVENT_RESET in state SIG_PRI_MOH_STATE_IDLE [May 27 10:02:06] DEBUG[12314][C-00000000] sig_pri.c: Channel 'DAHDI/i1/-1' MOH-Next-State: $ [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: DAHDI/i1/-1 Variable: TRANSFERCAPABILITY Value: SPEECH Uniqueid: 1432735326.2 [May 27 10:02:06] DEBUG[12314][C-00000000] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/i1/-1 [May 27 10:02:06] DEBUG[12314][C-00000000] chan_dahdi.c: Updated conferencing on 1, with 0 conference users [May 27 10:02:06] DEBUG[12314][C-00000000] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/i1/-1 [May 27 10:02:06] VERBOSE[12314][C-00000000] chan_dahdi.c: -- Hungup 'DAHDI/i1/-1' [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Hangup Privilege: call,all Channel: DAHDI/i1/-1 Uniqueid: 1432735326.2 CallerIDNum: CallerIDName: ConnectedLineNum: ConnectedLineName: AccountCode: Cause: 0 Cause-txt: Unknown [May 27 10:02:06] DEBUG[12152] devicestate.c: No provider found, checking channel drivers for DAHDI - i1/ [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: AVAILCHAN Value: DAHDI/i1/-1 Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: AVAILORIGCHAN Value: DAHDI/g0 Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: AVAILSTATUS Value: 0 Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: AVAILCAUSECODE Value: 0 Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Result of 'AVAILORIGCHAN' is 'DAHDI/g0' [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Expression result is '0' [May 27 10:02:06] DEBUG[12152] devicestate.c: Changing state for DAHDI/i1/ - state 0 (Unknown) [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Launching 'GotoIf' [May 27 10:02:06] DEBUG[12152] devicestate.c: device 'DAHDI/i1/' state '0' [May 27 10:02:06] VERBOSE[12314][C-00000000] pbx.c: -- Executing [9179629695@cfware_robodialer_entry:3] GotoIf("Local/9179629695@cfware_robodialer_entry-00000000;2", "0?notavail,1") in new stack [May 27 10:02:06] DEBUG[12190] app_queue.c: Device 'DAHDI/i1/' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newexten Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Context: cfware_robodialer_entry Extension: 9179629695 Priority: 3 Application: GotoIf AppData: 0?notavail,1 Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Not taking any branch [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Result of 'DIALER_INTERFACE' is 'DAHDI/g0' [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Result of 'EXTEN' is '9179629695' [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Result of 'ROBODIALER_TIMEOUT' is '30' [May 27 10:02:06] DEBUG[12314][C-00000000] pbx.c: Launching 'Dial' [May 27 10:02:06] VERBOSE[12314][C-00000000] pbx.c: -- Executing [9179629695@cfware_robodialer_entry:4] Dial("Local/9179629695@cfware_robodialer_entry-00000000;2", "DAHDI/g0/19179629695,30,g") in new stack [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newexten Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Context: cfware_robodialer_entry Extension: 9179629695 Priority: 4 Application: Dial AppData: DAHDI/g0/19179629695,30,g Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: DIALSTATUS Value: Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: DIALEDPEERNUMBER Value: Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: DIALEDPEERNAME Value: Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: ANSWEREDTIME Value: Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: DIALEDTIME Value: Uniqueid: 1432735326.1 [May 27 10:02:06] DEBUG[12314][C-00000000] chan_dahdi.c: Using channel 1 [May 27 10:02:06] DEBUG[12314][C-00000000] sig_pri.c: sig_pri_request 1 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newchannel Privilege: call,all Channel: DAHDI/i1/19179629695-2 ChannelState: 1 ChannelStateDesc: Rsrvd CallerIDNum: CallerIDName: AccountCode: Exten: Context: from-pstn Uniqueid: 1432735326.3 [May 27 10:02:06] DEBUG[12314][C-00000000] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [May 27 10:02:06] DEBUG[12314][C-00000000] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [May 27 10:02:06] DEBUG[12152] devicestate.c: No provider found, checking channel drivers for DAHDI - i1/19179629695 [May 27 10:02:06] DEBUG[12314][C-00000000] channel_internal_api.c: Channel Call ID changing from [C-00000000] to [C-00000000] [May 27 10:02:06] DEBUG[12152] devicestate.c: Changing state for DAHDI/i1/19179629695 - state 2 (In use) [May 27 10:02:06] DEBUG[12152] devicestate.c: device 'DAHDI/i1/19179629695' state '2' [May 27 10:02:06] DEBUG[12314][C-00000000] rtp_engine.c: Can't find native functions for channel 'DAHDI/i1/19179629695-2' [May 27 10:02:06] DEBUG[12314][C-00000000] sig_pri.c: CALLER NAME: NUM: 5184554100 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 -- Making new call for cref 32769 [May 27 10:02:06] DEBUG[12190] app_queue.c: Device 'DAHDI/i1/19179629695' changed to state '2' (In use) but we don't care because they're not a member of any queue. [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: DAHDIChannel Privilege: call,all Channel: DAHDI/i1/19179629695-2 Uniqueid: 1432735326.3 DAHDISpan: 1 DAHDIChannel: 1 [May 27 10:02:06] DEBUG[12314][C-00000000] sig_pri.c: prioffset: 1 mastertrunkgroup: 0 logicalspan: 0 result: 1 [May 27 10:02:06] VERBOSE[12314][C-00000000] sig_pri.c: -- Requested transfer capability: 0x00 - SPEECH [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: DAHDI/i1/19179629695-2 Variable: TRANSFERCAPABILITY Value: SPEECH Uniqueid: 1432735326.3 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > DL-DATA request [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: DAHDI/i1/19179629695-2 Variable: DIALEDPEERNUMBER Value: g0/19179629695 Uniqueid: 1432735326.3 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=47 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent from originator) [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: NewCallerid Privilege: call,all Channel: DAHDI/i1/19179629695-2 CallerIDNum: 9179629695 CallerIDName: Uniqueid: 1432735326.3 CID-CallingPres: 0 (Presentation Allowed, Not Screened) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Message Type: SETUP (5) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 TEI=0 Transmitting N(S)=0, window is open V(A)=0 K=7 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=47 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent from originator) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Message Type: SETUP (5) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > [04 03 80 90 a2] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > User information layer 1: u-Law (34) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > [18 03 a1 83 81] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Preferred Dchan: 0 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > ChanSel: As indicated in following octets [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Ext: 1 Coding: 0 Number Specified Channel Type: 3 [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Ext: 1 Channel: 1 Type: CPE] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > [1e 02 80 83] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > [6c 0c 21 81 35 31 38 34 35 35 34 31 30 30] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Calling Party Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Presentation: Presentation allowed, User-provided, verified and passed (1) '5184554100' ] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > [70 0c 80 31 39 31 37 39 36 32 39 36 39 35] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Called Party Number (len=14) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '19179629695' ] [May 27 10:02:06] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 q931.c:6350 q931_setup: Call 32769 enters state 1 (Call Initiated). Hold state: Idle [May 27 10:02:06] DEBUG[12152] devicestate.c: No provider found, checking channel drivers for DAHDI - i1/19179629695 [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Newstate Privilege: call,all Channel: DAHDI/i1/19179629695-2 ChannelState: 3 ChannelStateDesc: Dialing CallerIDNum: 9179629695 CallerIDName: ConnectedLineNum: 5184554100 ConnectedLineName: Uniqueid: 1432735326.3 [May 27 10:02:06] DEBUG[12152] devicestate.c: Changing state for DAHDI/i1/19179629695 - state 2 (In use) [May 27 10:02:06] DEBUG[12152] devicestate.c: device 'DAHDI/i1/19179629695' state '2' [May 27 10:02:06] DEBUG[12159] manager.c: Examining event: Event: Dial Privilege: call,all SubEvent: Begin Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Destination: DAHDI/i1/19179629695-2 CallerIDNum: 5184554100 CallerIDName: ConnectedLineNum: ConnectedLineName: UniqueID: 1432735326.1 DestUniqueID: 1432735326.3 Dialstring: g0/19179629695 [May 27 10:02:06] DEBUG[12190] app_queue.c: Device 'DAHDI/i1/19179629695' changed to state '2' (In use) but we don't care because they're not a member of any queue. [May 27 10:02:06] VERBOSE[12314][C-00000000] app_dial.c: -- Called DAHDI/g0/19179629695 [May 27 10:02:06] DEBUG[12314][C-00000000] channel.c: Set channel DAHDI/i1/19179629695-2 to read format slin [May 27 10:02:06] DEBUG[12314][C-00000000] channel.c: Set channel DAHDI/i1/19179629695-2 to write format slin [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=10 [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent to originator) [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Message Type: CALL PROCEEDING (2) [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < [18 03 a9 83 81] [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0 [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < ChanSel: As indicated in following octets [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Ext: 1 Coding: 0 Number Specified Channel Type: 3 [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Ext: 1 Channel: 1 Type: CPE] [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 Received message for call 0x7ff498011110 on link 0x1e991f0 TEI/SAPI 0/0 [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 -- Processing IE 24 (cs0, Channel ID) [May 27 10:02:06] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 q931.c:8846 post_handle_q931_message: Call 32769 enters state 3 (Outgoing Call Proceeding). Hold state: Idle [May 27 10:02:06] VERBOSE[12177] sig_pri.c: Span 1: Processing event PRI_EVENT_PROCEEDING(13) [May 27 10:02:06] DEBUG[12177][C-00000000] sig_pri.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1 [May 27 10:02:06] VERBOSE[12314][C-00000000] app_dial.c: -- DAHDI/i1/19179629695-2 is proceeding passing it to Local/9179629695@cfware_robodialer_entry-00000000;2 [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=13 [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent to originator) [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Message Type: PROGRESS (3) [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < [08 02 82 ff] [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Public network serving the local user (2) [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Ext: 1 Cause: Interworking, unspecified (127), class = Interworking (7) ] [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < [1e 02 82 81] [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2) [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Ext: 1 Progress Description: Call is not end-to-end ISDN; further call progress information may be available inband. (1) ] [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 Received message for call 0x7ff498011110 on link 0x1e991f0 TEI/SAPI 0/0 [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 -- Processing IE 8 (cs0, Cause) [May 27 10:02:07] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 -- Processing IE 30 (cs0, Progress Indicator) [May 27 10:02:07] VERBOSE[12177] sig_pri.c: Span 1: Processing event PRI_EVENT_PROGRESS(17) [May 27 10:02:07] VERBOSE[12177][C-00000000] sig_pri.c: -- PROGRESS with cause code 127 received [May 27 10:02:07] DEBUG[12177][C-00000000] sig_pri.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/1 span 1 [May 27 10:02:07] VERBOSE[12314][C-00000000] app_dial.c: -- DAHDI/i1/19179629695-2 is making progress passing it to Local/9179629695@cfware_robodialer_entry-00000000;2 [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=9 [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent to originator) [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Message Type: DISCONNECT (69) [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < [08 02 80 90] [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: User (0) [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 Received message for call 0x7ff498011110 on link 0x1e991f0 TEI/SAPI 0/0 [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 -- Processing IE 8 (cs0, Cause) [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 -- Found active call: 0x7ff498011110 cref:32769 [May 27 10:02:34] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 q931.c:9099 post_handle_q931_message: Call 32769 enters state 12 (Disconnect Indication). Hold state: Idle [May 27 10:02:34] VERBOSE[12177] sig_pri.c: Span 1: Processing event PRI_EVENT_HANGUP_REQ(15) [May 27 10:02:34] VERBOSE[12177][C-00000000] sig_pri.c: -- Span 1: Channel 0/1 got hangup request, cause 16 [May 27 10:02:34] DEBUG[12314][C-00000000] channel.c: Hanging up channel 'DAHDI/i1/19179629695-2' [May 27 10:02:34] DEBUG[12314][C-00000000] chan_dahdi.c: dahdi_hangup(DAHDI/i1/19179629695-2) [May 27 10:02:34] DEBUG[12314][C-00000000] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/i1/19179629695-2 [May 27 10:02:34] DEBUG[12314][C-00000000] sig_pri.c: sig_pri_hangup 1 [May 27 10:02:34] DEBUG[12314][C-00000000] sig_pri.c: Channel 'DAHDI/i1/19179629695-2' MOH-Event: SIG_PRI_MOH_EVENT_RESET in state SIG_PRI_MOH_STATE_IDLE [May 27 10:02:34] DEBUG[12314][C-00000000] sig_pri.c: Channel 'DAHDI/i1/19179629695-2' MOH-Next-State: $ [May 27 10:02:34] DEBUG[12314][C-00000000] sig_pri.c: Not yet hungup... Calling hangup with cause 16, and clearing call [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 q931.c:7151 q931_hangup: Hangup other cref:32769 [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 q931.c:6908 __q931_hangup: ourstate Disconnect Indication, peerstate Disconnect Request, hold-state Idle [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 q931.c:5946 q931_release: Call 32769 enters state 19 (Release Request). Hold state: Idle [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > DL-DATA request [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9 [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent from originator) [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Message Type: RELEASE (77) [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 TEI=0 Transmitting N(S)=1, window is open V(A)=1 K=7 [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9 [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent from originator) [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Message Type: RELEASE (77) [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > [08 02 81 90] [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) [May 27 10:02:34] VERBOSE[12314] chan_dahdi.c: PRI Span: 1 > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [May 27 10:02:34] DEBUG[12314][C-00000000] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/i1/19179629695-2 [May 27 10:02:34] DEBUG[12314][C-00000000] chan_dahdi.c: Updated conferencing on 1, with 0 conference users [May 27 10:02:34] DEBUG[12314][C-00000000] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/i1/19179629695-2 [May 27 10:02:34] VERBOSE[12314][C-00000000] chan_dahdi.c: -- Hungup 'DAHDI/i1/19179629695-2' [May 27 10:02:34] VERBOSE[12314][C-00000000] app_dial.c: -- No one is available to answer at this time (1:0/0/0) [May 27 10:02:34] DEBUG[12152] devicestate.c: No provider found, checking channel drivers for DAHDI - i1/19179629695 [May 27 10:02:34] DEBUG[12314][C-00000000] app_dial.c: Exiting with DIALSTATUS=NOANSWER. [May 27 10:02:34] DEBUG[12314][C-00000000] pbx.c: Result of 'DIALSTATUS' is 'NOANSWER' [May 27 10:02:34] DEBUG[12314][C-00000000] pbx.c: Launching 'NoOp' [May 27 10:02:34] DEBUG[12152] devicestate.c: Changing state for DAHDI/i1/19179629695 - state 0 (Unknown) [May 27 10:02:34] DEBUG[12152] devicestate.c: device 'DAHDI/i1/19179629695' state '0' [May 27 10:02:34] VERBOSE[12314][C-00000000] pbx.c: -- Executing [9179629695@cfware_robodialer_entry:5] NoOp("Local/9179629695@cfware_robodialer_entry-00000000;2", "NOANSWER") in new stack [May 27 10:02:34] DEBUG[12190] app_queue.c: Device 'DAHDI/i1/19179629695' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [May 27 10:02:34] DEBUG[12314][C-00000000] pbx.c: Result of 'HANGUPCAUSE' is '16' [May 27 10:02:34] DEBUG[12314][C-00000000] pbx.c: Launching 'Hangup' [May 27 10:02:34] VERBOSE[12314][C-00000000] pbx.c: -- Executing [9179629695@cfware_robodialer_entry:6] Hangup("Local/9179629695@cfware_robodialer_entry-00000000;2", "16") in new stack [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: Hangup Privilege: call,all Channel: DAHDI/i1/19179629695-2 Uniqueid: 1432735326.3 CallerIDNum: 9179629695 CallerIDName: ConnectedLineNum: 5184554100 ConnectedLineName: AccountCode: Cause: 16 Cause-txt: Normal Clearing [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Variable: DIALSTATUS Value: NOANSWER Uniqueid: 1432735326.1 [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: Dial Privilege: call,all SubEvent: End Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 UniqueID: 1432735326.1 DialStatus: NOANSWER [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: Newexten Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Context: cfware_robodialer_entry Extension: 9179629695 Priority: 5 Application: NoOp AppData: NOANSWER Uniqueid: 1432735326.1 [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: Newexten Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Context: cfware_robodialer_entry Extension: 9179629695 Priority: 6 Application: Hangup AppData: 16 Uniqueid: 1432735326.1 [May 27 10:02:34] DEBUG[12314][C-00000000] channel.c: Soft-Hanging up channel 'Local/9179629695@cfware_robodialer_entry-00000000;2' [May 27 10:02:34] DEBUG[12314][C-00000000] pbx.c: Spawn extension (cfware_robodialer_entry,9179629695,6) exited non-zero on 'Local/9179629695@cfware_robodialer_entry-00000000;2' [May 27 10:02:34] VERBOSE[12314][C-00000000] pbx.c: == Spawn extension (cfware_robodialer_entry, 9179629695, 6) exited non-zero on 'Local/9179629695@cfware_robodialer_entry-00000000;2' [May 27 10:02:34] DEBUG[12314][C-00000000] channel.c: Soft-Hanging up channel 'Local/9179629695@cfware_robodialer_entry-00000000;2' [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: SoftHangupRequest Privilege: call,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Uniqueid: 1432735326.1 Cause: 16 [May 27 10:02:34] DEBUG[12314][C-00000000] channel.c: Hanging up channel 'Local/9179629695@cfware_robodialer_entry-00000000;2' [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: VarSet Privilege: dialplan,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 Variable: CHANLOCALSTATUS Value: NOANSWER Uniqueid: 1432735326.0 [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: Hangup Privilege: call,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;2 Uniqueid: 1432735326.1 CallerIDNum: 5184554100 CallerIDName: ConnectedLineNum: ConnectedLineName: AccountCode: Cause: 16 Cause-txt: Normal Clearing [May 27 10:02:34] DEBUG[12313][C-00000000] channel.c: Hanging up channel 'Local/9179629695@cfware_robodialer_entry-00000000;1' [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: Hangup Privilege: call,all Channel: Local/9179629695@cfware_robodialer_entry-00000000;1 Uniqueid: 1432735326.0 CallerIDNum: CallerIDName: ConnectedLineNum: ConnectedLineName: AccountCode: Cause: 16 Cause-txt: Normal Clearing [May 27 10:02:34] DEBUG[12159] manager.c: Examining event: Event: OriginateResponse Privilege: call,all ActionID: 10553 Response: Failure Channel: LOCAL/9179629695@cfware_robodialer_entry Context: cfware_robodialer_amd Exten: s Reason: 1 Uniqueid: CallerIDNum: CallerIDName: [May 27 10:02:34] DEBUG[12152] devicestate.c: No provider found, checking channel drivers for Local - 9179629695@cfware_robodialer_entry [May 27 10:02:34] DEBUG[12152] chan_local.c: Checking if extension 9179629695@cfware_robodialer_entry exists (devicestate) [May 27 10:02:34] DEBUG[12152] devicestate.c: Changing state for Local/9179629695@cfware_robodialer_entry - state 1 (Not in use) [May 27 10:02:34] DEBUG[12152] devicestate.c: device 'Local/9179629695@cfware_robodialer_entry' state '1' [May 27 10:02:34] DEBUG[12152] devicestate.c: No provider found, checking channel drivers for Local - 9179629695@cfware_robodialer_entry [May 27 10:02:34] DEBUG[12152] chan_local.c: Checking if extension 9179629695@cfware_robodialer_entry exists (devicestate) [May 27 10:02:34] DEBUG[12152] devicestate.c: Changing state for Local/9179629695@cfware_robodialer_entry - state 1 (Not in use) [May 27 10:02:34] DEBUG[12152] devicestate.c: device 'Local/9179629695@cfware_robodialer_entry' state '1' [May 27 10:02:34] DEBUG[12190] app_queue.c: Device 'Local/9179629695@cfware_robodialer_entry' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 27 10:02:34] DEBUG[12190] app_queue.c: Device 'Local/9179629695@cfware_robodialer_entry' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=5 [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent to originator) [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 < Message Type: RELEASE COMPLETE (90) [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 Received message for call 0x7ff498011110 on link 0x1e991f0 TEI/SAPI 0/0 [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 q931.c:8959 post_handle_q931_message: Call 32769 enters state 0 (Null). Hold state: Idle [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 q931.c:7151 q931_hangup: Hangup other cref:32769 [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 q931.c:6908 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle [May 27 10:02:35] VERBOSE[12177] chan_dahdi.c: PRI Span: 1 Destroying call 0x7ff498011110, ourstate Null, peerstate Null, hold-state Idle [May 27 10:02:35] VERBOSE[12177] sig_pri.c: Span 1: Processing event PRI_EVENT_HANGUP_ACK(9)