Aug 5 01:36:05 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Aug 5 01:36:05 VERBOSE[22018] logger.c: Timestamp: 00008ms SCall: 00001 DCall: 00000 [10.255.40.11:4569] Aug 5 01:36:05 VERBOSE[22018] logger.c: VERSION : 2 Aug 5 01:36:05 VERBOSE[22018] logger.c: CALLED NUMBER : 916045825332 Aug 5 01:36:05 VERBOSE[22018] logger.c: CODEC_PREFS : (ulaw) Aug 5 01:36:05 VERBOSE[22018] logger.c: CALLING NUMBER : 6101 Aug 5 01:36:05 VERBOSE[22018] logger.c: CALLING PRESNTN : 0 Aug 5 01:36:05 VERBOSE[22018] logger.c: CALLING TYPEOFN : 0 Aug 5 01:36:05 VERBOSE[22018] logger.c: CALLING TRANSIT : 0 Aug 5 01:36:05 VERBOSE[22018] logger.c: CALLING NAME : WharfRdDAN Aug 5 01:36:05 VERBOSE[22018] logger.c: LANGUAGE : en Aug 5 01:36:05 VERBOSE[22018] logger.c: CALLED CONTEXT : out-pstn Aug 5 01:36:05 VERBOSE[22018] logger.c: FORMAT : 4 Aug 5 01:36:05 VERBOSE[22018] logger.c: CAPABILITY : 63492 Aug 5 01:36:05 VERBOSE[22018] logger.c: ADSICPE : 2 Aug 5 01:36:05 VERBOSE[22018] logger.c: DATE TIME : 2005-08-05 01:36:10 Aug 5 01:36:05 VERBOSE[22018] logger.c: Aug 5 01:36:05 DEBUG[22018] acl.c: ##### Testing 10.255.40.11 with 0.0.0.0 Aug 5 01:36:05 DEBUG[22018] acl.c: ##### Testing 10.255.40.11 with 0.0.0.0 Aug 5 01:36:05 DEBUG[22018] acl.c: ##### Testing 10.255.40.11 with 0.0.0.0 Aug 5 01:36:05 DEBUG[22018] acl.c: ##### Testing 10.255.40.11 with 0.0.0.0 Aug 5 01:36:05 VERBOSE[22018] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Aug 5 01:36:05 VERBOSE[22018] logger.c: Timestamp: 00004ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:05 VERBOSE[22018] logger.c: AUTHMETHODS : 3 Aug 5 01:36:05 VERBOSE[22018] logger.c: CHALLENGE : 832486114 Aug 5 01:36:05 VERBOSE[22018] logger.c: USERNAME : astpbx-woodbay-incoming Aug 5 01:36:05 VERBOSE[22018] logger.c: Aug 5 01:36:05 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP Aug 5 01:36:05 VERBOSE[22018] logger.c: Timestamp: 00032ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:05 VERBOSE[22018] logger.c: MD5 RESULT : 338914ab6ac1f674f8d7eea38dbc6bbf Aug 5 01:36:05 VERBOSE[22018] logger.c: Aug 5 01:36:05 VERBOSE[22018] logger.c: -- Accepting AUTHENTICATED call from 10.255.40.11: > requested format = ulaw, > requested prefs = disabled, > actual format = ulaw, > host prefs = disabled, > priority = reqonly Aug 5 01:36:05 VERBOSE[22018] logger.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Aug 5 01:36:05 VERBOSE[22018] logger.c: Timestamp: 00014ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:05 VERBOSE[22018] logger.c: FORMAT : 4 Aug 5 01:36:05 VERBOSE[22018] logger.c: Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Executing Set("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "dialednumber=916045825332") in new stack Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Executing Goto("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "out-pstn-set-slb|s|1") in new stack Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Goto (out-pstn-set-slb,s,1) Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Executing Set("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "TIMEOUT(response)=1") in new stack Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Response timeout set to 1 Aug 5 01:36:05 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Aug 5 01:36:05 VERBOSE[22018] logger.c: Timestamp: 00014ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Executing Set("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "InternalCALLERIDNUM=6101") in new stack Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Executing Goto("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "6101|1") in new stack Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Goto (out-pstn-set-slb,6101,1) Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Sent into invalid extension '6101' in context 'out-pstn-set-slb' on IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1 Aug 5 01:36:05 VERBOSE[22138] logger.c: -- Executing SetCallerID("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "<6048852261>") in new stack Aug 5 01:36:05 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: VOICE Subclass: 4 Aug 5 01:36:05 VERBOSE[22018] logger.c: Timestamp: 00060ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:05 DEBUG[22018] chan_iax2.c: Ooh, voice format changed to 4 Aug 5 01:36:05 VERBOSE[22018] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Aug 5 01:36:05 VERBOSE[22018] logger.c: Timestamp: 00060ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:06 VERBOSE[22138] logger.c: -- Timeout on IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1 Aug 5 01:36:06 VERBOSE[22138] logger.c: == CDR updated on IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1 Aug 5 01:36:06 VERBOSE[22138] logger.c: -- Executing NoOp("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "Translated Internal CallerIDNum 6101 to SLB CallerIDNum 6048852261") in new stack Aug 5 01:36:06 VERBOSE[22138] logger.c: -- Executing Goto("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "out-pstn|dodial|1") in new stack Aug 5 01:36:06 VERBOSE[22138] logger.c: -- Goto (out-pstn,dodial,1) Aug 5 01:36:07 VERBOSE[22138] logger.c: -- Executing SetVar("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "CALLFILENAME=from6101-as6048852261-to16045825332-at20050805-013607") in new stack Aug 5 01:36:07 VERBOSE[22138] logger.c: -- Executing NoOp("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "Monitor(wav|from6101-as6048852261-to16045825332-at20050805-013607)") in new stack Aug 5 01:36:07 VERBOSE[22138] logger.c: -- Executing Dial("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "zap/G1/16045825332||g") in new stack Aug 5 01:36:07 VERBOSE[22138] logger.c: -- Making new call for cr 32775 Aug 5 01:36:07 DEBUG[22012] channel.c: Avoiding initial deadlock for 'Zap/11-1' Aug 5 01:36:07 VERBOSE[22138] logger.c: -- Requested transfer capability: 0x00 - SPEECH Aug 5 01:36:07 VERBOSE[22138] logger.c: > Protocol Discriminator: Q.931 (8) len=47 Aug 5 01:36:07 VERBOSE[22138] logger.c: > Call Ref: len= 2 (reference 7/0x7) (Originator) Aug 5 01:36:07 DEBUG[22012] channel.c: Avoiding initial deadlock for 'Zap/11-1' Aug 5 01:36:07 VERBOSE[22138] logger.c: > Message type: SETUP (5) Aug 5 01:36:07 VERBOSE[22138] logger.c: > [Aug 5 01:36:07 VERBOSE[22138] logger.c: > [04Aug 5 01:36:07 VERBOSE[22138] logger.c: > [04 03Aug 5 01:36:07 VERBOSE[22138] logger.c: > [04 03 80Aug 5 01:36:07 VERBOSE[22138] logger.c: > [04 03 80 90Aug 5 01:36:07 VERBOSE[22138] logger.c: > [04 03 80 90 a2Aug 5 01:36:07 VERBOSE[22138] logger.c: > [04 03 80 90 a2] Aug 5 01:36:07 DEBUG[22012] channel.c: Avoiding initial deadlock for 'Zap/11-1' Aug 5 01:36:07 VERBOSE[22138] logger.c: > Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0) Aug 5 01:36:07 VERBOSE[22138] logger.c: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) Aug 5 01:36:07 VERBOSE[22138] logger.c: > Ext: 1 User information layer 1: u-Law (34) Aug 5 01:36:07 VERBOSE[22138] logger.c: > [Aug 5 01:36:07 VERBOSE[22138] logger.c: > [18Aug 5 01:36:07 VERBOSE[22138] logger.c: > [18 03Aug 5 01:36:07 VERBOSE[22138] logger.c: > [18 03 a9Aug 5 01:36:07 VERBOSE[22138] logger.c: > [18 03 a9 83Aug 5 01:36:07 VERBOSE[22138] logger.c: > [18 03 a9 83 8bAug 5 01:36:07 DEBUG[22012] channel.c: Avoiding initial deadlock for 'Zap/11-1' Aug 5 01:36:07 VERBOSE[22138] logger.c: > [18 03 a9 83 8b] Aug 5 01:36:07 VERBOSE[22138] logger.c: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0 Aug 5 01:36:07 VERBOSE[22138] logger.c: > ChanSel: Reserved Aug 5 01:36:07 VERBOSE[22138] logger.c: > Ext: 1 Coding: 0 Number Specified Channel Type: 3 Aug 5 01:36:07 VERBOSE[22138] logger.c: > Ext: 1 Channel: 11 ] Aug 5 01:36:07 VERBOSE[22138] logger.c: > [Aug 5 01:36:07 VERBOSE[22138] logger.c: > [1eAug 5 01:36:07 VERBOSE[22138] logger.c: > [1e 02Aug 5 01:36:07 VERBOSE[22138] logger.c: > [1e 02 80Aug 5 01:36:07 VERBOSE[22138] logger.c: > [1e 02 80 83Aug 5 01:36:07 DEBUG[22012] channel.c: Avoiding initial deadlock for 'Zap/11-1' Aug 5 01:36:07 VERBOSE[22138] logger.c: > [1e 02 80 83] Aug 5 01:36:07 VERBOSE[22138] logger.c: > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0) Aug 5 01:36:07 VERBOSE[22138] logger.c: > Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ] Aug 5 01:36:07 VERBOSE[22138] logger.c: > [Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6cAug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0cAug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34 38Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34 38 38Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34 38 38 35Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34 38 38 35 32Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34 38 38 35 32 32Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34 38 38 35 32 32 36Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34 38 38 35 32 32 36 31Aug 5 01:36:07 DEBUG[22012] channel.c: Avoiding initial deadlock for 'Zap/11-1' Aug 5 01:36:07 VERBOSE[22138] logger.c: > [6c 0c 21 80 36 30 34 38 38 35 32 32 36 31] Aug 5 01:36:07 VERBOSE[22138] logger.c: > Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) Aug 5 01:36:07 VERBOSE[22138] logger.c: > Presentation: Presentation permitted, user number not screened (0) '6048852261' ] Aug 5 01:36:07 VERBOSE[22138] logger.c: > [Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0cAug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34 35Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34 35 38Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34 35 38 32Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34 35 38 32 35Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34 35 38 32 35 33Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34 35 38 32 35 33 33Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34 35 38 32 35 33 33 32Aug 5 01:36:07 DEBUG[22012] channel.c: Avoiding initial deadlock for 'Zap/11-1' Aug 5 01:36:07 VERBOSE[22138] logger.c: > [70 0c 80 31 36 30 34 35 38 32 35 33 33 32] Aug 5 01:36:07 VERBOSE[22138] logger.c: > Called Number (len=14) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '16045825332' ] Aug 5 01:36:07 VERBOSE[22138] logger.c: -- Called G1/16045825332 Aug 5 01:36:07 DEBUG[22138] chan_iax2.c: predicted timestamp skew (1103) > max (160), using real ts instead. Aug 5 01:36:07 VERBOSE[22018] logger.c: Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 003 Type: VOICE Subclass: 4 Aug 5 01:36:07 VERBOSE[22018] logger.c: Timestamp: 01120ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:07 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: IAX Subclass: ACK Aug 5 01:36:07 VERBOSE[22018] logger.c: Timestamp: 01120ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:07 VERBOSE[22023] logger.c: < Protocol Discriminator: Q.931 (8) len=10 Aug 5 01:36:07 VERBOSE[22023] logger.c: < Call Ref: len= 2 (reference 7/0x7) (Terminator) Aug 5 01:36:07 VERBOSE[22023] logger.c: < Message type: CALL PROCEEDING (2) Aug 5 01:36:07 VERBOSE[22023] logger.c: < [Aug 5 01:36:07 VERBOSE[22023] logger.c: < [18Aug 5 01:36:07 VERBOSE[22023] logger.c: < [18 03Aug 5 01:36:07 VERBOSE[22023] logger.c: < [18 03 a9Aug 5 01:36:07 VERBOSE[22023] logger.c: < [18 03 a9 83Aug 5 01:36:07 VERBOSE[22023] logger.c: < [18 03 a9 83 8bAug 5 01:36:07 VERBOSE[22023] logger.c: < [18 03 a9 83 8b] Aug 5 01:36:07 VERBOSE[22023] logger.c: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0 Aug 5 01:36:07 VERBOSE[22023] logger.c: < ChanSel: Reserved Aug 5 01:36:07 VERBOSE[22023] logger.c: < Ext: 1 Coding: 0 Number Specified Channel Type: 3 Aug 5 01:36:07 VERBOSE[22023] logger.c: < Ext: 1 Channel: 11 ] Aug 5 01:36:07 VERBOSE[22023] logger.c: -- Processing IE 24 (cs0, Channel Identification) Aug 5 01:36:07 DEBUG[22023] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/11 span 1 Aug 5 01:36:07 VERBOSE[22138] logger.c: -- Zap/11-1 is proceeding passing it to IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1 Aug 5 01:36:07 VERBOSE[22018] logger.c: Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 003 Type: CONTROL Subclass: (15?) Aug 5 01:36:07 VERBOSE[22018] logger.c: Timestamp: 01263ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:07 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 004 Type: IAX Subclass: ACK Aug 5 01:36:07 VERBOSE[22018] logger.c: Timestamp: 01263ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:08 VERBOSE[22023] logger.c: < Protocol Discriminator: Q.931 (8) len=9 Aug 5 01:36:08 VERBOSE[22023] logger.c: < Call Ref: len= 2 (reference 7/0x7) (Terminator) Aug 5 01:36:08 VERBOSE[22023] logger.c: < Message type: PROGRESS (3) Aug 5 01:36:08 VERBOSE[22023] logger.c: < [Aug 5 01:36:08 VERBOSE[22023] logger.c: < [1eAug 5 01:36:08 VERBOSE[22023] logger.c: < [1e 02Aug 5 01:36:08 VERBOSE[22023] logger.c: < [1e 02 8aAug 5 01:36:08 VERBOSE[22023] logger.c: < [1e 02 8a 81Aug 5 01:36:08 VERBOSE[22023] logger.c: < [1e 02 8a 81] Aug 5 01:36:08 VERBOSE[22023] logger.c: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Network beyond the interworking point (10) Aug 5 01:36:08 VERBOSE[22023] logger.c: < Ext: 1 Progress Description: Call is not end-to-end ISDN; further call progress information may be available inband. (1) ] Aug 5 01:36:08 VERBOSE[22023] logger.c: -- Processing IE 30 (cs0, Progress Indicator) Aug 5 01:36:08 DEBUG[22023] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/11 span 1 Aug 5 01:36:08 VERBOSE[22138] logger.c: -- Zap/11-1 is making progress passing it to IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1 Aug 5 01:36:08 VERBOSE[22018] logger.c: Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 003 Type: CONTROL Subclass: (14?) Aug 5 01:36:08 VERBOSE[22018] logger.c: Timestamp: 02523ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:08 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK Aug 5 01:36:08 VERBOSE[22018] logger.c: Timestamp: 02523ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX Subclass: LAGRQ Aug 5 01:36:15 VERBOSE[22018] logger.c: Timestamp: 10009ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 VERBOSE[22018] logger.c: Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 004 Type: IAX Subclass: LAGRP Aug 5 01:36:15 VERBOSE[22018] logger.c: Timestamp: 10009ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 006 Type: IAX Subclass: ACK Aug 5 01:36:15 VERBOSE[22018] logger.c: Timestamp: 10009ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 VERBOSE[22018] logger.c: Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 004 Type: IAX Subclass: LAGRQ Aug 5 01:36:15 VERBOSE[22018] logger.c: Timestamp: 10021ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 007 Type: IAX Subclass: LAGRP Aug 5 01:36:15 VERBOSE[22018] logger.c: Timestamp: 10021ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 VERBOSE[22018] logger.c: Tx-Frame Retry[-01] -- OSeqno: 007 ISeqno: 005 Type: IAX Subclass: ACK Aug 5 01:36:15 VERBOSE[22018] logger.c: Timestamp: 10021ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 VERBOSE[22018] logger.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 007 Type: IAX Subclass: HANGUP Aug 5 01:36:15 VERBOSE[22018] logger.c: Timestamp: 10040ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 VERBOSE[22018] logger.c: CAUSE CODE : 0 Aug 5 01:36:15 VERBOSE[22018] logger.c: Aug 5 01:36:15 DEBUG[22018] chan_iax2.c: Immediately destroying 1, having received hangup Aug 5 01:36:15 VERBOSE[22018] logger.c: Tx-Frame Retry[-01] -- OSeqno: 007 ISeqno: 006 Type: IAX Subclass: ACK Aug 5 01:36:15 VERBOSE[22018] logger.c: Timestamp: 10040ms SCall: 00001 DCall: 00001 [10.255.40.11:4569] Aug 5 01:36:15 DEBUG[22138] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/11-1 Aug 5 01:36:15 DEBUG[22138] chan_zap.c: Hangup: channel: 11 index = 0, normal = 34, callwait = -1, thirdcall = -1 Aug 5 01:36:15 DEBUG[22138] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call Aug 5 01:36:15 VERBOSE[22138] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Outgoing call Proceeding, peerstate Incoming Call Proceeding Aug 5 01:36:15 VERBOSE[22138] logger.c: > Protocol Discriminator: Q.931 (8) len=9 Aug 5 01:36:15 VERBOSE[22138] logger.c: > Call Ref: len= 2 (reference 7/0x7) (Originator) Aug 5 01:36:15 VERBOSE[22138] logger.c: > Message type: DISCONNECT (69) Aug 5 01:36:15 VERBOSE[22138] logger.c: > [Aug 5 01:36:15 VERBOSE[22138] logger.c: > [08Aug 5 01:36:15 VERBOSE[22138] logger.c: > [08 02Aug 5 01:36:15 VERBOSE[22138] logger.c: > [08 02 81Aug 5 01:36:15 VERBOSE[22138] logger.c: > [08 02 81 90Aug 5 01:36:15 VERBOSE[22138] logger.c: > [08 02 81 90] Aug 5 01:36:15 VERBOSE[22138] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) Aug 5 01:36:15 VERBOSE[22138] logger.c: > Ext: 1 Cause: Unknown (16), class = Normal Event (1) ] Aug 5 01:36:15 DEBUG[22138] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/11-1 Aug 5 01:36:15 DEBUG[22138] chan_zap.c: Updated conferencing on 11, with 0 conference users Aug 5 01:36:15 DEBUG[22138] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/11-1 Aug 5 01:36:15 VERBOSE[22138] logger.c: -- Hungup 'Zap/11-1' Aug 5 01:36:15 DEBUG[22138] app_dial.c: Exiting with DIALSTATUS=CANCEL. Aug 5 01:36:15 VERBOSE[22138] logger.c: == Spawn extension (out-pstn, dodial, 3) exited non-zero on 'IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1' Aug 5 01:36:15 VERBOSE[22138] logger.c: -- Executing NoOp("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "cleaning up monitoring files as the user never made it to the quality debug menu") in new stack Aug 5 01:36:15 VERBOSE[22138] logger.c: -- Executing System("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "/bin/rm -f /var/spool/asterisk/monitor/from6101-as6048852261-to16045825332-at20050805-013607*") in new stack Aug 5 01:36:15 VERBOSE[22138] logger.c: -- Executing Hangup("IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1", "") in new stack Aug 5 01:36:15 VERBOSE[22138] logger.c: == Spawn extension (out-pstn, h, 3) exited non-zero on 'IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1' Aug 5 01:36:15 DEBUG[22138] chan_iax2.c: We're hanging up IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1 now... Aug 5 01:36:15 DEBUG[22138] chan_iax2.c: Really destroying IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1 now... Aug 5 01:36:15 VERBOSE[22138] logger.c: -- Hungup 'IAX2/astpbx-woodbay-incoming@astpbx-wharfrd-1' Aug 5 01:36:16 VERBOSE[22023] logger.c: < Protocol Discriminator: Q.931 (8) len=5 Aug 5 01:36:16 VERBOSE[22023] logger.c: < Call Ref: len= 2 (reference 7/0x7) (Terminator) Aug 5 01:36:16 VERBOSE[22023] logger.c: < Message type: RELEASE (77) Aug 5 01:36:16 VERBOSE[22023] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request Aug 5 01:36:16 VERBOSE[22023] logger.c: > Protocol Discriminator: Q.931 (8) len=9 Aug 5 01:36:16 VERBOSE[22023] logger.c: > Call Ref: len= 2 (reference 7/0x7) (Originator) Aug 5 01:36:16 VERBOSE[22023] logger.c: > Message type: RELEASE COMPLETE (90) Aug 5 01:36:16 VERBOSE[22023] logger.c: > [Aug 5 01:36:16 VERBOSE[22023] logger.c: > [08Aug 5 01:36:16 VERBOSE[22023] logger.c: > [08 02Aug 5 01:36:16 VERBOSE[22023] logger.c: > [08 02 81Aug 5 01:36:16 VERBOSE[22023] logger.c: > [08 02 81 90Aug 5 01:36:16 VERBOSE[22023] logger.c: > [08 02 81 90] Aug 5 01:36:16 VERBOSE[22023] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) Aug 5 01:36:16 VERBOSE[22023] logger.c: > Ext: 1 Cause: Unknown (16), class = Normal Event (1) ] Aug 5 01:36:16 VERBOSE[22023] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null Aug 5 01:36:16 VERBOSE[22023] logger.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null Aug 5 01:37:06 VERBOSE[22038] logger.c: -- Remote UNIX connection disconnected