[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Protocol Discriminator: Q.931 (8)  len=47
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Call Ref: len= 2 (reference 25051/0x61DB) (Originator)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Message Type: SETUP (5)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [a1]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Sending Complete (len= 1)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [04 03 90 90 a3]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: 3.1kHz audio (16)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: <                                User information layer 1: A-Law (35)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [18 03 a9 83 9f]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: <                        ChanSel: As indicated in following octets
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: <                       Ext: 1  Channel: 31 Type: CPE]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [1e 02 82 83]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Public network serving the local user (2)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: <                               Ext: 1  Progress Description: Calling equipment is non-ISDN. (3) ]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [1e 02 a1 90]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Progress Indicator (len= 4) [ Ext: 1  Coding: Non-ITU international standard (1)  0: 0  Location: Private network serving the local user (1)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: <                               Ext: 1  Progress Description: Interworking with a public network. (16) ]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [6c 0a 21 83 33 35 35 35 31 32 33 34]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Calling Number (len=12) [ Ext: 0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: <                           Presentation: Presentation allowed of network provided number (3)  '35551234' ]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [70 05 80 39 30 39 32]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Called Number (len= 7) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '9092' ]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [9d]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Non-Locking Shift (len=01): Requested codeset 5
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < [32 01 80]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: < Calling Party Category (len= 3) [ Ext: 1  Cat: Unknown Source (0) ]
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Making new call for cr 25051
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: Received message for call 0xb66085d8 on 0x9de01d8 TEI/SAPI 0/0, call->pri is 0x9de01d8 TEI/SAPI 0/0
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing Q.931 Call Setup
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing IE 161 (cs0, Sending Complete)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing IE 4 (cs0, Bearer Capability)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing IE 24 (cs0, Channel Identification)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing IE 30 (cs0, Progress Indicator)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing IE 30 (cs0, Progress Indicator)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing IE 108 (cs0, Calling Party Number)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing IE 112 (cs0, Called Party Number)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: -- Processing IE 50 (cs5, Calling Party Category)
[Jan 29 08:34:44] VERBOSE[10430] chan_dahdi.c: q931.c:6608 post_handle_q931_message: Call 25051 enters state 6 (Call Present).  Hold state: Idle
[Jan 29 08:34:44] DEBUG[10428] chan_sip.c: Auto destroying SIP dialog '00164668-2f4c0002-04759fad-191a1260@192.168.xxx.xxx'
[Jan 29 08:34:44] DEBUG[10428] chan_sip.c: Destroying SIP dialog 00164668-2f4c0002-04759fad-191a1260@192.168.xxx.xxx
[Jan 29 08:34:52] DEBUG[10446] chan_iax2.c: ip callno count decremented to 0 for 192.168.xxx.xxx
[Jan 29 08:34:52] DEBUG[10439] chan_iax2.c: ip callno count incremented to 1 for 192.168.xxx.xxx
[Jan 29 08:34:52] DEBUG[10440] chan_iax2.c: schedule decrement of callno used for 192.168.xxx.xxx in 60 seconds
[Jan 29 08:34:52] DEBUG[10440] chan_iax2.c: Peer apricot: got pong, lastms 3, historicms 2, maxms 2000
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Protocol Discriminator: Q.931 (8)  len=47
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Call Ref: len= 2 (reference 25051/0x61DB) (Originator)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Message Type: SETUP (5)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [a1]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Sending Complete (len= 1)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [04 03 90 90 a3]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: 3.1kHz audio (16)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: <                                User information layer 1: A-Law (35)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [18 03 a9 83 9f]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: <                        ChanSel: As indicated in following octets
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: <                       Ext: 1  Channel: 31 Type: CPE]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [1e 02 82 83]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Public network serving the local user (2)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: <                               Ext: 1  Progress Description: Calling equipment is non-ISDN. (3) ]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [1e 02 a1 90]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Progress Indicator (len= 4) [ Ext: 1  Coding: Non-ITU international standard (1)  0: 0  Location: Private network serving the local user (1)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: <                               Ext: 1  Progress Description: Interworking with a public network. (16) ]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [6c 0a 21 83 33 35 35 35 31 32 33 34]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Calling Number (len=12) [ Ext: 0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: <                           Presentation: Presentation allowed of network provided number (3)  '35551234' ]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [70 05 80 39 30 39 32]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Called Number (len= 7) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '9092' ]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [9d]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Non-Locking Shift (len=01): Requested codeset 5
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < [32 01 80]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: < Calling Party Category (len= 3) [ Ext: 1  Cat: Unknown Source (0) ]
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: Received message for call 0xb66085d8 on 0x9de01d8 TEI/SAPI 0/0, call->pri is 0x9de01d8 TEI/SAPI 0/0
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing Q.931 Call Setup
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing IE 161 (cs0, Sending Complete)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing IE 4 (cs0, Bearer Capability)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing IE 24 (cs0, Channel Identification)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing IE 30 (cs0, Progress Indicator)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing IE 30 (cs0, Progress Indicator)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing IE 108 (cs0, Calling Party Number)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing IE 112 (cs0, Called Party Number)
[Jan 29 08:34:53] VERBOSE[10430] chan_dahdi.c: -- Processing IE 50 (cs5, Calling Party Category)
[Jan 29 08:34:56] DEBUG[10446] chan_iax2.c: ip callno count decremented to 0 for 192.168.xxx.xxx
[Jan 29 08:34:56] DEBUG[10443] chan_iax2.c: ip callno count incremented to 1 for 192.168.xxx.xxx
[Jan 29 08:34:56] DEBUG[10444] chan_iax2.c: schedule decrement of callno used for 192.168.xxx.xxx in 60 seconds
[Jan 29 08:34:56] DEBUG[10444] chan_iax2.c: Peer xxxxxxx: got pong, lastms 8, historicms 8, maxms 2000
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: < Protocol Discriminator: Q.931 (8)  len=12
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: < Call Ref: len= 2 (reference 25051/0x61DB) (Originator)
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: < Message Type: RELEASE (77)
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: < [08 05 82 e6 33 31 30]
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: < Cause (len= 7) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Public network serving the local user (2)
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: <                  Ext: 1  Cause: Recover on timer expiry (102), class = Protocol Error (e.g. unknown message) (6) ]
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: <              Cause data: 33 31 30 (Timer T310)
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: Received message for call 0xb66085d8 on 0x9de01d8 TEI/SAPI 0/0, call->pri is 0x9de01d8 TEI/SAPI 0/0
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: -- Processing IE 8 (cs0, Cause)
[Jan 29 08:34:58] VERBOSE[10430] chan_dahdi.c: q931.c:6860 post_handle_q931_message: Call 25051 enters state 0 (Null).  Hold state: Idle
[Jan 29 08:34:58] WARNING[10430] chan_dahdi.c: Call specified, but not found?
[Jan 29 08:34:58] WARNING[10430] chan_dahdi.c: Hangup on bad channel 0/31 on span 1