[Mar 17 12:04:18] VERBOSE[3450] logger.c: < Protocol Discriminator: Q.931 (8) len=46 [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Call Ref: len= 2 (reference 22/0x16) (Originator) [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Message type: SETUP (5) [Mar 17 12:04:18] VERBOSE[3450] logger.c: < [a1] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Sending Complete (len= 1) [Mar 17 12:04:18] VERBOSE[3450] logger.c: < [04 02 88 90] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Bearer Capability (len= 4) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Unrestricted digital information (8) [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Ext: 0 User information layer 1: Unknown (24) [Mar 17 12:04:18] VERBOSE[3450] logger.c: < [18 03 a9 83 82] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0 [Mar 17 12:04:18] VERBOSE[3450] logger.c: < ChanSel: Reserved [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Ext: 1 Coding: 0 Number Specified Channel Type: 3 [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Ext: 1 Channel: 2 ] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < [6c 0c 01 81 33 34 36 33 39 37 37 38 39 38] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Calling Number (len=14) [ Ext: 0 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Presentation: Presentation permitted, user number passed network screening (1) '3463977898' ] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < [70 0a a1 35 34 39 39 33 39 30 30 30] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < Called Number (len=12) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '549939000' ] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < [7c 03 88 90 a6] [Mar 17 12:04:18] VERBOSE[3450] logger.c: < IE: Low-layer Compatibility (len = 5) [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Making new call for cr 22 [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Processing Q.931 Call Setup [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Processing IE 161 (cs0, Sending Complete) [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Processing IE 4 (cs0, Bearer Capability) [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Processing IE 24 (cs0, Channel Identification) [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Processing IE 108 (cs0, Calling Party Number) [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Processing IE 112 (cs0, Called Party Number) [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Processing IE 124 (cs0, Low-layer Compatibility) [Mar 17 12:04:18] VERBOSE[3450] logger.c: q931.c:3298 q931_receive: call 22 on channel 2 enters state 6 (Call Present) [Mar 17 12:04:18] DEBUG[3450] chan_zap.c: PRI_EVENT_RING: seting incoming call on channel 95 to law=1 [Mar 17 12:04:18] DEBUG[3450] chan_zap.c: PRI_EVENT_RING: user information layer 1 of incoming call = 24 (0x18) [Mar 17 12:04:18] DEBUG[3450] chan_zap.c: PRI_EVENT_RING: treat incoming call on channel 95 as ULAW(1) [Mar 17 12:04:18] DEBUG[3450] chan_zap.c: PRI_EVENT_RING: seting incoming call on channel 95 to law=1 [Mar 17 12:04:18] VERBOSE[3450] logger.c: q931.c:2571 q931_call_proceeding: call 22 on channel 2 enters state 9 (Incoming Call Proceeding) [Mar 17 12:04:18] VERBOSE[3450] logger.c: > Protocol Discriminator: Q.931 (8) len=10 [Mar 17 12:04:18] VERBOSE[3450] logger.c: > Call Ref: len= 2 (reference 22/0x16) (Terminator) [Mar 17 12:04:18] VERBOSE[3450] logger.c: > Message type: CALL PROCEEDING (2) [Mar 17 12:04:18] VERBOSE[3450] logger.c: > [18 03 a9 83 82] [Mar 17 12:04:18] VERBOSE[3450] logger.c: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0 [Mar 17 12:04:18] VERBOSE[3450] logger.c: > ChanSel: Reserved [Mar 17 12:04:18] VERBOSE[3450] logger.c: > Ext: 1 Coding: 0 Number Specified Channel Type: 3 [Mar 17 12:04:18] VERBOSE[3450] logger.c: > Ext: 1 Channel: 2 ] [Mar 17 12:04:18] DEBUG[3450] chan_zap.c: PRI_EVENT_RING: zt_new: law=1,ul1=24 [Mar 17 12:04:18] DEBUG[3450] chan_zap.c: zt_new: ps.curlaw!=ZT_LAW_ALAW, setting deflaw to AST_FORMAT_ULAW [Mar 17 12:04:18] DEBUG[3450] chan_zap.c: zt_new: law=ZT_LAW_ALAW, setting deflaw to AST_FORMAT_ULAW [Mar 17 12:04:18] VERBOSE[3450] logger.c: -- Accepting call from '3463977898' to '549939000' on channel 0/2, span 4 [Mar 17 12:04:18] DEBUG[3450] chan_zap.c: Echo cancellation isn't required on digital connection [Mar 17 12:04:18] VERBOSE[3497] logger.c: -- Executing [549939000@from-pstn:1] h324m_call("Zap/95-1", "549939000@h324m-decoded") in new stack [Mar 17 12:04:18] DEBUG[3497] app_h324m.c: h324m_call [Mar 17 12:04:18] VERBOSE[3498] logger.c: -- Executing [549939000@h324m-decoded:1] h324m_call("Local/549939000@h324m-decoded-835f,2", "549939000@h324m-decoded-encoded") in new stack [Mar 17 12:04:18] DEBUG[3498] app_h324m.c: h324m_call [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- Executing [549939000@h324m-decoded-encoded:1] Set("Local/549939000@h324m-decoded-encoded-7cd7,2", "CHANNEL(transfercapability)=VIDEO") in new stack [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- Executing [549939000@h324m-decoded-encoded:2] NoOp("Local/549939000@h324m-decoded-encoded-7cd7,2", "transfer=VIDEO") in new stack [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- Executing [549939000@h324m-decoded-encoded:3] Set("Local/549939000@h324m-decoded-encoded-7cd7,2", "CHANNEL(userinformationlayer1)=38") in new stack [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- Executing [549939000@h324m-decoded-encoded:4] NoOp("Local/549939000@h324m-decoded-encoded-7cd7,2", "ul1=38") in new stack [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- Executing [549939000@h324m-decoded-encoded:5] Dial("Local/549939000@h324m-decoded-encoded-7cd7,2", "Zap/g0/3468442617") in new stack [Mar 17 12:04:18] DEBUG[3499] chan_zap.c: zt_new: ps.curlaw=ZT_LAW_ALAW, setting deflaw to AST_FORMAT_ALAW [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- Making new call for cr 32770 [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- digital call, setting user information layer 1 to 38 (0x26) [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- zap call: h324musellc=0, ast->userinformationlayer1=38 [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- Requested transfer capability: 0x18 - VIDEO [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Protocol Discriminator: Q.931 (8) len=42 [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Call Ref: len= 2 (reference 2/0x2) (Originator) [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Message type: SETUP (5) [Mar 17 12:04:18] VERBOSE[3499] logger.c: > [04 03 88 90 a6] [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Unrestricted digital information (8) [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Ext: 1 User information layer 1: H.223 and H.245 (38) [Mar 17 12:04:18] VERBOSE[3499] logger.c: > [18 03 a9 83 81] [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0 [Mar 17 12:04:18] VERBOSE[3499] logger.c: > ChanSel: Reserved [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Ext: 1 Coding: 0 Number Specified Channel Type: 3 [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Ext: 1 Channel: 1 ] [Mar 17 12:04:18] VERBOSE[3499] logger.c: > [6c 0c 21 80 33 34 36 33 39 37 37 38 39 38] [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Presentation: Presentation permitted, user number not screened (0) '3463977898' ] [Mar 17 12:04:18] VERBOSE[3499] logger.c: > [70 0b c1 33 34 36 38 34 34 32 36 31 37] [Mar 17 12:04:18] VERBOSE[3499] logger.c: > Called Number (len=13) [ Ext: 1 TON: Subscriber Number (4) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '3468442617' ] [Mar 17 12:04:18] VERBOSE[3499] logger.c: q931.c:2881 q931_setup: call 32770 on channel 1 enters state 1 (Call Initiated) [Mar 17 12:04:18] VERBOSE[3499] logger.c: -- Called g0/3468442617 [Mar 17 12:04:19] VERBOSE[3450] logger.c: < Protocol Discriminator: Q.931 (8) len=10 [Mar 17 12:04:19] VERBOSE[3450] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) [Mar 17 12:04:19] VERBOSE[3450] logger.c: < Message type: SETUP ACKNOWLEDGE (13) [Mar 17 12:04:19] VERBOSE[3450] logger.c: < [18 03 a9 83 81] [Mar 17 12:04:19] VERBOSE[3450] logger.c: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0 [Mar 17 12:04:19] VERBOSE[3450] logger.c: < ChanSel: Reserved [Mar 17 12:04:19] VERBOSE[3450] logger.c: < Ext: 1 Coding: 0 Number Specified Channel Type: 3 [Mar 17 12:04:19] VERBOSE[3450] logger.c: < Ext: 1 Channel: 1 ] [Mar 17 12:04:19] VERBOSE[3450] logger.c: -- Processing IE 24 (cs0, Channel Identification) [Mar 17 12:04:19] VERBOSE[3450] logger.c: q931.c:3622 q931_receive: call 32770 on channel 1 enters state 2 (Overlap sending) [Mar 17 12:04:24] VERBOSE[3450] logger.c: < Protocol Discriminator: Q.931 (8) len=5 [Mar 17 12:04:24] VERBOSE[3450] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) [Mar 17 12:04:24] VERBOSE[3450] logger.c: < Message type: ALERTING (1) [Mar 17 12:04:24] VERBOSE[3450] logger.c: q931.c:3343 q931_receive: call 32770 on channel 1 enters state 4 (Call Delivered) [Mar 17 12:04:24] DEBUG[3450] chan_zap.c: Echo cancellation isn't required on digital connection [Mar 17 12:04:24] VERBOSE[3499] logger.c: -- Zap/94-1 is ringing [Mar 17 12:04:24] VERBOSE[3497] logger.c: q931.c:2599 q931_alerting: call 22 on channel 2 enters state 7 (Call Received) [Mar 17 12:04:24] VERBOSE[3497] logger.c: > Protocol Discriminator: Q.931 (8) len=5 [Mar 17 12:04:24] VERBOSE[3497] logger.c: > Call Ref: len= 2 (reference 22/0x16) (Terminator) [Mar 17 12:04:24] VERBOSE[3497] logger.c: > Message type: ALERTING (1) [Mar 17 12:04:28] VERBOSE[3450] logger.c: < Protocol Discriminator: Q.931 (8) len=16 [Mar 17 12:04:28] VERBOSE[3450] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) [Mar 17 12:04:28] VERBOSE[3450] logger.c: < Message type: CONNECT (7) [Mar 17 12:04:28] VERBOSE[3450] logger.c: < [29 05 08 03 11 0a 3b] [Mar 17 12:04:28] VERBOSE[3450] logger.c: < Time Date (len= 7) [ [Mar 17 12:04:28] VERBOSE[3450] logger.c: 08[Mar 17 12:04:28] VERBOSE[3450] logger.c: -03[Mar 17 12:04:28] VERBOSE[3450] logger.c: -17[Mar 17 12:04:28] VERBOSE[3450] logger.c: 10[Mar 17 12:04:28] VERBOSE[3450] logger.c: :59[Mar 17 12:04:28] VERBOSE[3450] logger.c: ] [Mar 17 12:04:28] VERBOSE[3450] logger.c: < [4c 02 00 a3] [Mar 17 12:04:28] VERBOSE[3450] logger.c: < Connected Number (len= 4) [ Ext: 0 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0)[Mar 17 12:04:28] VERBOSE[3450] logger.c: < Ext: 1 Presentation: Presentation prohibited of network provided number (35)[Mar 17 12:04:28] VERBOSE[3450] logger.c: '' ] [Mar 17 12:04:28] VERBOSE[3450] logger.c: -- Processing IE 41 (cs0, Date/Time) [Mar 17 12:04:28] VERBOSE[3450] logger.c: -- Processing IE 76 (cs0, Connected Number) [Mar 17 12:04:28] VERBOSE[3450] logger.c: q931.c:3373 q931_receive: call 32770 on channel 1 enters state 10 (Active) [Mar 17 12:04:28] VERBOSE[3450] logger.c: > Protocol Discriminator: Q.931 (8) len=5 [Mar 17 12:04:28] VERBOSE[3450] logger.c: > Call Ref: len= 2 (reference 2/0x2) (Originator) [Mar 17 12:04:28] VERBOSE[3450] logger.c: > Message type: CONNECT ACKNOWLEDGE (15) [Mar 17 12:04:28] DEBUG[3450] chan_zap.c: Echo cancellation isn't required on digital connection [Mar 17 12:04:28] VERBOSE[3499] logger.c: -- Zap/94-1 answered Local/549939000@h324m-decoded-encoded-7cd7,2 [Mar 17 12:04:28] DEBUG[3498] chan_zap.c: New owner for channel 94 is Zap/94-1 [Mar 17 12:04:28] VERBOSE[3499] logger.c: == Spawn extension (h324m-decoded-encoded, 549939000, 5) exited non-zero on 'Local/549939000@h324m-decoded-encoded-7cd7,2' [Mar 17 12:04:28] DEBUG[3498] app_h324m.c: H324M changed state 1 [Mar 17 12:04:30] DEBUG[3498] app_h324m.c: H324M changed state 3 [Mar 17 12:04:30] DEBUG[3498] app_h324m.c: Connected, sending VIDUPDATE [Mar 17 12:04:30] DEBUG[3498] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:30] DEBUG[3498] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:30] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:30] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:30] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:30] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Protocol Discriminator: Q.931 (8) len=9 [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Message type: DISCONNECT (69) [Mar 17 12:04:51] VERBOSE[3450] logger.c: < [08 02 80 90] [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: User (0) [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [Mar 17 12:04:51] VERBOSE[3450] logger.c: -- Processing IE 8 (cs0, Cause) [Mar 17 12:04:51] VERBOSE[3450] logger.c: q931.c:3563 q931_receive: call 32770 on channel 1 enters state 12 (Disconnect Indication) [Mar 17 12:04:51] VERBOSE[3450] logger.c: -- Channel 0/1, span 4 got hangup request, cause 16 [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: init_h324m_packetizer: found 1 AMR frames inside ast_frame [Mar 17 12:04:51] DEBUG[3497] app_h324m.c: create_h324m_frame: processing AMR frame #1 inside ast_frame [Mar 17 12:04:51] DEBUG[3498] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/94-1 [Mar 17 12:04:51] DEBUG[3498] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call [Mar 17 12:04:51] VERBOSE[3498] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request [Mar 17 12:04:51] VERBOSE[3498] logger.c: q931.c:2716 q931_release: call 32770 on channel 1 enters state 19 (Release Request) [Mar 17 12:04:51] VERBOSE[3498] logger.c: > Protocol Discriminator: Q.931 (8) len=9 [Mar 17 12:04:51] VERBOSE[3498] logger.c: > Call Ref: len= 2 (reference 2/0x2) (Originator) [Mar 17 12:04:51] VERBOSE[3498] logger.c: > Message type: RELEASE (77) [Mar 17 12:04:51] VERBOSE[3498] logger.c: > [08 02 81 90] [Mar 17 12:04:51] VERBOSE[3498] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) [Mar 17 12:04:51] VERBOSE[3498] logger.c: > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [Mar 17 12:04:51] DEBUG[3498] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/94-1 [Mar 17 12:04:51] VERBOSE[3498] logger.c: -- Hungup 'Zap/94-1' [Mar 17 12:04:51] VERBOSE[3498] logger.c: == Auto fallthrough, channel 'Local/549939000@h324m-decoded-835f,2' status is 'UNKNOWN' [Mar 17 12:04:51] VERBOSE[3497] logger.c: == Auto fallthrough, channel 'Zap/95-1' status is 'UNKNOWN' [Mar 17 12:04:51] DEBUG[3497] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/95-1 [Mar 17 12:04:51] DEBUG[3497] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call [Mar 17 12:04:51] VERBOSE[3497] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Received, peerstate Call Delivered [Mar 17 12:04:51] VERBOSE[3497] logger.c: q931.c:2764 q931_disconnect: call 22 on channel 2 enters state 11 (Disconnect Request) [Mar 17 12:04:51] VERBOSE[3497] logger.c: > Protocol Discriminator: Q.931 (8) len=9 [Mar 17 12:04:51] VERBOSE[3497] logger.c: > Call Ref: len= 2 (reference 22/0x16) (Terminator) [Mar 17 12:04:51] VERBOSE[3497] logger.c: > Message type: DISCONNECT (69) [Mar 17 12:04:51] VERBOSE[3497] logger.c: > [08 02 81 90] [Mar 17 12:04:51] VERBOSE[3497] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) [Mar 17 12:04:51] VERBOSE[3497] logger.c: > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [Mar 17 12:04:51] DEBUG[3497] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/95-1 [Mar 17 12:04:51] VERBOSE[3497] logger.c: -- Hungup 'Zap/95-1' [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Protocol Discriminator: Q.931 (8) len=5 [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Call Ref: len= 2 (reference 2/0x2) (Terminator) [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Message type: RELEASE COMPLETE (90) [Mar 17 12:04:51] VERBOSE[3450] logger.c: q931.c:3503 q931_receive: call 32770 on channel 1 enters state 0 (Null) [Mar 17 12:04:51] VERBOSE[3450] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null [Mar 17 12:04:51] VERBOSE[3450] logger.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Protocol Discriminator: Q.931 (8) len=5 [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Call Ref: len= 2 (reference 22/0x16) (Originator) [Mar 17 12:04:51] VERBOSE[3450] logger.c: < Message type: RELEASE (77) [Mar 17 12:04:51] VERBOSE[3450] logger.c: q931.c:3538 q931_receive: call 22 on channel 2 enters state 0 (Null) [Mar 17 12:04:51] VERBOSE[3450] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request [Mar 17 12:04:51] VERBOSE[3450] logger.c: > Protocol Discriminator: Q.931 (8) len=9 [Mar 17 12:04:51] VERBOSE[3450] logger.c: > Call Ref: len= 2 (reference 22/0x16) (Terminator) [Mar 17 12:04:51] VERBOSE[3450] logger.c: > Message type: RELEASE COMPLETE (90) [Mar 17 12:04:51] VERBOSE[3450] logger.c: > [08 02 81 90] [Mar 17 12:04:51] VERBOSE[3450] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) [Mar 17 12:04:51] VERBOSE[3450] logger.c: > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [Mar 17 12:04:51] VERBOSE[3450] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null [Mar 17 12:04:51] VERBOSE[3450] logger.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null