On Fri March 10 2006 10:55, Daniele Orlandi wrote:
> On Wednesday 08 March 2006 18:45, Maurizio Marini wrote:
> >
> > q931 visdn0:TEI[0]: DL-RELEASE-INDICATION
>
> Could you please post all the debugging related to call 75.O, unfortunately
> they're missing and I cannot reconstruct the whole trace.
>
> Thanks,
> Bye,
>
i hope this is what u are searching for :)
call 75.O logs starts on 18:01:25 and close on 18:17:55
phone call lasts for 8 seconds, instead
Mar 8 18:01:25 DEBUG[16374] acl.c: ##### Testing 192.168.1.21 with 192.168.1.0
Mar 8 18:01:25 DEBUG[16374] chan_sip.c: Allocating new SIP dialog for [EMAIL
PROTECTED] - INVITE (With RTP)
Mar 8 18:01:25 DEBUG[16374] chan_sip.c: * SIP extension value: 1 for call
[EMAIL PROTECTED]
Mar 8 18:01:25 DEBUG[16374] chan_sip.c: Setting NAT on RTP to 0
Mar 8 18:01:25 DEBUG[16374] chan_sip.c: Stopping retransmission on '[EMAIL
PROTECTED]' of Response 29141: Match Found
Mar 8 18:01:25 DEBUG[16374] chan_sip.c: Setting NAT on RTP to 0
Mar 8 18:01:25 DEBUG[16374] chan_sip.c: Checking SIP call limits for device 108
Mar 8 18:01:25 DEBUG[16374] chan_sip.c: build_route: Contact hop: <sip:[EMAIL
PROTECTED]>
Mar 8 18:01:25 DEBUG[16518] pbx.c: Launching 'NoOp'
Mar 8 18:01:25 DEBUG[16518] pbx.c: Launching 'Dial'
Mar 8 18:01:25 DEBUG[16518] channel.c: Not copying variable
STACK-from-sip-93396250470-2.
Mar 8 18:01:25 DEBUG[16518] channel.c: Not copying variable
STACK-from-sip-93396250470-1.
Mar 8 18:01:25 DEBUG[16518] channel.c: Not copying variable SIPCALLID.
Mar 8 18:01:25 DEBUG[16518] channel.c: Not copying variable SIPUSERAGENT.
Mar 8 18:01:25 DEBUG[16518] channel.c: Not copying variable SIPDOMAIN.
Mar 8 18:01:25 DEBUG[16518] channel.c: Not copying variable SIPURI.
Mar 8 18:01:25 VERBOSE[16518] logger.c: -- Calling huntgroup:telco on
VISDN/null
Mar 8 18:01:25 VERBOSE[16518] logger.c: -- Hunting started on group
'telco' (mode='sequential', cur_intf='', first_intf='', int_member='')
Mar 8 18:01:25 VERBOSE[16518] logger.c: -- Huntgroup: trying interface
'visdn0'
Mar 8 18:01:25 VERBOSE[16518] logger.c: -- Huntgroup: found interface
'visdn0'
Mar 8 18:01:25 VERBOSE[16518] logger.c: -- Calling on interface 'visdn0'
Mar 8 18:01:25 VERBOSE[16518] logger.c: q931 visdn0:CALL[75.O]: call.c:311 GET
(1 => 2)
Mar 8 18:01:25 VERBOSE[16518] logger.c: q931 visdn0:CALL[75.O]:
chan_visdn.c:793 GET (2 => 3)
Mar 8 18:01:25 VERBOSE[16518] logger.c: q931 visdn0:CALL[75.O]:
chan_visdn.c:328 GET (3 => 4)
Mar 8 18:01:25 VERBOSE[16518] logger.c: q931 visdn0:CALL[75.O]:
chan_visdn.c:915 PUT (4 => 3)
Mar 8 18:01:25 DEBUG[16518] channel.c: Set channel VISDN/visdn0/75.O to read
format alaw
Mar 8 18:01:25 DEBUG[16518] channel.c: Set channel SIP/108-eff8 to write
format alaw
Mar 8 18:01:25 DEBUG[16518] channel.c: Set channel SIP/108-eff8 to read format
alaw
Mar 8 18:01:25 DEBUG[16518] channel.c: Set channel VISDN/visdn0/75.O to write
format alaw
Mar 8 18:01:25 DEBUG[16519] app_queue.c: Device 'SIP/108' changed to state '2'
(In use)
Mar 8 18:01:25 DEBUG[16520] app_queue.c: Device 'VISDN/visdn0/75.O' changed to
state '0' (Unknown)
Mar 8 18:01:33 DEBUG[16518] channel.c: Hanging up channel 'VISDN/visdn0/75.O'
Mar 8 18:01:33 VERBOSE[16518] logger.c: -- visdn_hangup VISDN/visdn0/75.O
Mar 8 18:01:33 VERBOSE[16518] logger.c: q931 visdn0:CALL[75.O]:
chan_visdn.c:1816 PUT (3 => 2)
Mar 8 18:01:33 VERBOSE[16518] logger.c: -- visdn_hangup VISDN/visdn0/75.O
DONE
Mar 8 18:01:33 DEBUG[16518] app_dial.c: Exiting with DIALSTATUS=CANCEL.
Mar 8 18:01:33 DEBUG[16518] pbx.c: Spawn extension (from-sip,93396250470,2)
exited non-zero on 'SIP/108-eff8'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '"sirio" <108>'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '108'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '93396250470'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is 'from-sip'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is 'SIP/108-eff8'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is 'VISDN/visdn0/75.O'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is 'Dial'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is
'VISDN/huntgroup:telco/3396250470'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '2006-03-08 18:01:25'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '(null)'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '2006-03-08 18:01:33'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '8'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '0'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is 'NO ANSWER'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is 'DOCUMENTATION'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '(null)'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '1141837285.15'
Mar 8 18:01:33 DEBUG[16518] pbx.c: Function result is '(null)'
Mar 8 18:01:33 DEBUG[16518] cdr_addon_mysql.c: cdr_mysql: inserting a CDR
record.
Mar 8 18:01:33 DEBUG[16518] cdr_addon_mysql.c: cdr_mysql: SQL command as
follows: INSERT INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield)
VALUES ('2006-03-08 18:01:25','\"sirio\"
<108>','108','93396250470','from-sip',
'SIP/108-eff8','VISDN/visdn0/75.O','Dial','VISDN/huntgroup:telco/3396250470',8,0,'NO
ANSWER',3,'','')
Mar 8 18:01:33 DEBUG[16518] channel.c: Hanging up channel 'SIP/108-eff8'
Mar 8 18:01:33 DEBUG[16518] chan_sip.c: Hangup call SIP/108-eff8, SIP callid
[EMAIL PROTECTED])
Mar 8 18:01:33 DEBUG[16518] chan_sip.c: update_call_counter(108) - decrement
call limit counter
Mar 8 18:01:33 DEBUG[16521] app_queue.c: Device 'VISDN/visdn0/75.O' changed to
state '0' (Unknown)
Mar 8 18:01:33 DEBUG[16522] app_queue.c: Device 'VISDN/visdn0/75.O' changed to
state '0' (Unknown)
Mar 8 18:01:33 DEBUG[16523] app_queue.c: Device 'SIP/108' changed to state '1'
(Not in use)
Mar 8 18:01:33 DEBUG[16374] chan_sip.c: Stopping retransmission on '[EMAIL
PROTECTED]' of Response 29142: Match Found
Mar 8 18:17:39 DEBUG[16374] acl.c: ##### Testing 192.168.1.13 with 192.168.1.0
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: Allocating new SIP dialog for [EMAIL
PROTECTED] - INVITE (With RTP)
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: * SIP extension value: 1 for call
[EMAIL PROTECTED]
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: Setting NAT on RTP to 0
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: Stopping retransmission on '[EMAIL
PROTECTED]' of Response 34837: Match Found
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: Setting NAT on RTP to 0
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: Checking SIP call limits for device 104
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: build_route: Contact hop: <sip:[EMAIL
PROTECTED];user=phone>
Mar 8 18:17:39 DEBUG[16534] pbx.c: Launching 'NoOp'
Mar 8 18:17:39 DEBUG[16534] pbx.c: Launching 'Dial'
Mar 8 18:17:39 DEBUG[16534] chan_sip.c: Allocating new SIP dialog for (No
Call-ID) - INVITE (With RTP)
Mar 8 18:17:39 DEBUG[16534] chan_sip.c: Setting NAT on RTP to 0
Mar 8 18:17:39 DEBUG[16534] acl.c: ##### Testing 192.168.1.21 with 192.168.1.0
Mar 8 18:17:39 DEBUG[16534] channel.c: Not copying variable
STACK-from-sip-108-2.
Mar 8 18:17:39 DEBUG[16534] channel.c: Not copying variable
STACK-from-sip-108-1.
Mar 8 18:17:39 DEBUG[16534] channel.c: Not copying variable SIPCALLID.
Mar 8 18:17:39 DEBUG[16534] channel.c: Not copying variable SIPUSERAGENT.
Mar 8 18:17:39 DEBUG[16534] channel.c: Not copying variable SIPDOMAIN.
Mar 8 18:17:39 DEBUG[16534] channel.c: Not copying variable SIPURI.
Mar 8 18:17:39 DEBUG[16534] chan_sip.c: Outgoing Call for 108
Mar 8 18:17:39 DEBUG[16534] channel.c: Set channel SIP/108-7330 to read format
slin
Mar 8 18:17:39 DEBUG[16534] channel.c: Set channel SIP/104-3279 to write
format slin
Mar 8 18:17:39 DEBUG[16534] channel.c: Set channel SIP/104-3279 to read format
slin
Mar 8 18:17:39 DEBUG[16534] channel.c: Set channel SIP/108-7330 to write
format slin
Mar 8 18:17:39 DEBUG[16535] app_queue.c: Device 'SIP/104' changed to state '2'
(In use)
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: (Provisional) Stopping retransmission
(but retaining packet) on '[EMAIL PROTECTED]' Request 102: Found
Mar 8 18:17:39 DEBUG[16374] chan_sip.c: (Provisional) Stopping retransmission
(but retaining packet) on '[EMAIL PROTECTED]' Request 102: Found
Mar 8 18:17:39 DEBUG[16536] app_queue.c: Device 'SIP/108' changed to state '6'
(Ringing)
Mar 8 18:17:46 DEBUG[16374] chan_sip.c: Acked pending invite 102
Mar 8 18:17:46 DEBUG[16374] chan_sip.c: Stopping retransmission on '[EMAIL
PROTECTED]' of Request 102: Match Found
Mar 8 18:17:46 DEBUG[16374] chan_sip.c: build_route: Contact hop: <sip:[EMAIL
PROTECTED]>
Mar 8 18:17:46 DEBUG[16534] channel.c: Set channel SIP/104-3279 to read format
slin
Mar 8 18:17:46 DEBUG[16534] channel.c: Set channel SIP/108-7330 to write
format slin
Mar 8 18:17:46 DEBUG[16534] channel.c: Set channel SIP/108-7330 to read format
slin
Mar 8 18:17:46 DEBUG[16534] channel.c: Set channel SIP/104-3279 to write
format slin
Mar 8 18:17:46 DEBUG[16534] chan_sip.c: sip_answer(SIP/104-3279)
Mar 8 18:17:46 DEBUG[16537] app_queue.c: Device 'SIP/108' changed to state '2'
(In use)
Mar 8 18:17:46 DEBUG[16538] app_queue.c: Device 'SIP/104' changed to state '2'
(In use)
Mar 8 18:17:46 DEBUG[16534] rtp.c: Ooh, format changed from unknown to ulaw
Mar 8 18:17:46 DEBUG[16374] chan_sip.c: Stopping retransmission on '[EMAIL
PROTECTED]' of Response 34838: Match Found
Mar 8 18:17:46 DEBUG[16534] rtp.c: Ooh, format changed from unknown to alaw
Mar 8 18:17:55 VERBOSE[16381] logger.c: -- In-band informations available
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]:
chan_visdn.c:328 GET (3 => 4)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]:
chan_visdn.c:4158 PUT (4 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: -- poll timeout = -1
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: RELEASE-REQ
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: Sending
message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> message type: RELEASE (77)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> VL IE 0 ===> 8 (Cause) --
length 2
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Location = User (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Cause value = Normal call
clearing (16)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: call.c:393 GET
(3 => 4)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: call.c:1670
Timer T308 started
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]:
U12_DISCONNECT_INDICATION ==to==> U19_RELEASE_REQUEST
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]:
chan_visdn.c:2201 PUT (4 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: SETUP-REQ
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: Sending
message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> message type: SETUP (5)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> VL IE 0 ===> 4 (Bearer
Capability) -- length 3
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Coding Standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Information Transfer
Capability = Speech (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Transfer mode = Circuit (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Information Transfer Rate =
64 kbps (16)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> User information layer 1
protocol = g.711 a-law (3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> VL IE 1 ===> 108 (Calling
Party Number) -- length 5
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Type of number = Unknown (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Numbering plan = Unknown (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Presentation indicator =
Presentation allowed (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Screening indicator = User
provided, not screened (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Number = 108
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> VL IE 2 ===> 112 (Called
Party Number) -- length 11
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Type of number = Unknown (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Numbering plan = Unknown (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Number = 3396250470
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> VL IE 3 ===> 125 (High Layer
Compatibility) -- length 2
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Characteristics
identification = Telephony (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:393 GET
(2 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:1901
Timer T303 started
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: U0_NULL_STATE
==to==> U1_CALL_INITIATED
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]:
chan_visdn.c:2201 PUT (3 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: RELEASE-REQ
Mar 8 18:17:55 WARNING[16381] libq931: visdn0:CALL[25.I]: Unexpected
q931_release_request in state U19_RELEASE_REQUEST
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]:
chan_visdn.c:2201 PUT (3 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Received message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- call reference = 25.I (len 1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- message_type = RELEASE (77)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: call.c:421 GET
(2 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: Got RELEASE
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 1 ===> 8 (Cause)
length=(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Location = User (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Cause value = Normal call
clearing (16)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: call.c:405 PUT
(3 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: call.c:5082
Timer T308 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0[B1]: changed state from
DISCONNECTED to AVAILABLE
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]:
U19_RELEASE_REQUEST ==to==> U0_NULL_STATE
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: call.c:373 PUT
(2 => 1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]:
chan_visdn.c:362 GET (1 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: input.c:1196
PUT (2 => 1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[127]: Received message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- call reference = 46.I (len 1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- message_type = SETUP (5)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]: call.c:277 GET
(1 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]: Got SETUP
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 1 ===> 4 (Bearer
Capability) length=(3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Coding Standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Information Transfer
Capability = Speech (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Transfer mode = Circuit (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Information Transfer Rate =
64 kbps (16)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- User information layer 1
protocol = g.711 a-law (3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 2 ===> 24 (Channel
Identification) length=(1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Interface id = Implicit (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Interface type = Basic (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Pref/Excl = Exclusive (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- D channel ident = Is not D
channel (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Channels = B1
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 3 ===> 30 (Progress
Indicator) length=(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Location = User (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Description = Origination
address is non-ISDN (3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 4 ===> 108 (Calling
Party Number) length=(11)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Type of number = National
(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Numbering plan = ISDN
Telephony (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Presentation indicator =
Presentation allowed (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Screening indicator =
Network provided (3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Number = 243982036
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 5 ===> 112 (Called
Party Number) length=(10)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Type of number = National
(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Numbering plan = ISDN
Telephony (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Number = 721855285
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- SO IE 6 ===> 161 (Sending
Complete) length=(1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]: Requested
channel B1 available
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]: U0_NULL_STATE
==to==> U6_CALL_PRESENT
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]:
chan_visdn.c:362 GET (2 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]: input.c:1196
PUT (3 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[127]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[127]: Releasing DLC
Mar 8 18:17:55 VERBOSE[16381] logger.c: -- poll timeout = 4995
Mar 8 18:17:55 VERBOSE[16381] logger.c: -- visdn_q931_disconnect_channel
Mar 8 18:17:55 VERBOSE[16381] logger.c: -- visdn_q931_release_confirm
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]:
chan_visdn.c:4158 PUT (1 => 0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: Freeing call
Mar 8 18:17:55 VERBOSE[16381] logger.c: -- visdn_q931_setup_indication
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]:
chan_visdn.c:3087 GET (2 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]:
chan_visdn.c:328 GET (3 => 4)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]:
chan_visdn.c:4158 PUT (4 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Received message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- call reference = 25.I (len 1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- message_type = RELEASE (77)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: call.c:277 GET
(1 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: Received a
RELEASE for an unknown callref
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: Sending
message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> message type: RELEASE
COMPLETE (90)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> VL IE 0 ===> 8 (Cause) --
length 2
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Location = Private network
serving remote user (5)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Cause value = Invalid call
reference value (81)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: call.c:373 PUT
(2 => 1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: input.c:1205
PUT (1 => 0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[25.I]: Freeing call
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[127]: Received message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- call reference = 117.I (len 1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- message_type = SETUP (5)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]: call.c:277
GET (1 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]: Got SETUP
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 1 ===> 4 (Bearer
Capability) length=(3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Coding Standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Information Transfer
Capability = 3.1 kHz audio (16)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Transfer mode = Circuit (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Information Transfer Rate =
64 kbps (16)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- User information layer 1
protocol = g.711 a-law (3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 2 ===> 24 (Channel
Identification) length=(1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Interface id = Implicit (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Interface type = Basic (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Pref/Excl = Exclusive (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- D channel ident = Is not D
channel (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Channels = B1
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 3 ===> 30 (Progress
Indicator) length=(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Location = Public network
serving local user (2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Description = Origination
address is non-ISDN (3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 4 ===> 108 (Calling
Party Number) length=(11)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Type of number = National
(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Numbering plan = ISDN
Telephony (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Presentation indicator =
Presentation allowed (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Screening indicator =
Network provided (3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Number = 113975973
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- VL IE 5 ===> 112 (Called
Party Number) length=(10)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Type of number = National
(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Numbering plan = ISDN
Telephony (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- Number = 721855285
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 <- SO IE 6 ===> 161 (Sending
Complete) length=(1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]: Requested
channel B1 available
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]: U0_NULL_STATE
==to==> U6_CALL_PRESENT
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]:
chan_visdn.c:362 GET (2 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]: input.c:1196
PUT (3 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[127]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[127]: Releasing DLC
Mar 8 18:17:55 VERBOSE[16381] logger.c: -- poll timeout = 4987
Mar 8 18:17:55 VERBOSE[16381] logger.c: -- visdn_q931_setup_indication
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]:
chan_visdn.c:3087 GET (2 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]:
chan_visdn.c:328 GET (3 => 4)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]:
chan_visdn.c:4158 PUT (4 => 3)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]: PROCEEDING-REQ
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]: Sending
message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> message type: CALL PROCEEDING
(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> VL IE 0 ===> 24 (Channel
Identification) -- length 1
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Interface id = Implicit (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Interface type = Basic (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Pref/Excl = Exclusive (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> D channel ident = Is not D
channel (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Channels = B1
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]:
U6_CALL_PRESENT ==to==> U9_INCOMING_CALL_PROCEEDING
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[46.I]:
chan_visdn.c:2201 PUT (3 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]: PROCEEDING-REQ
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]: Sending
message:
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> message type: CALL PROCEEDING
(2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> VL IE 0 ===> 24 (Channel
Identification) -- length 1
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Interface id = Implicit (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Interface type = Basic (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Pref/Excl = Exclusive (1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> D channel ident = Is not D
channel (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Coding standard = CCITT (0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 -> Channels = B1
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]: Releasing message
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]:
U6_CALL_PRESENT ==to==> U9_INCOMING_CALL_PROCEEDING
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[117.I]:
chan_visdn.c:2201 PUT (3 => 2)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:TEI[0]:
DL-RELEASE-INDICATION
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:432
Timer T301 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:433
Timer T302 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:405 PUT
(2 => 1)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:434
Timer T303 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:435
Timer T304 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:436
Timer T305 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:437
Timer T306 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:438
Timer T308 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:439
Timer T309 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:440
Timer T310 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:441
Timer T312 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:442
Timer T313 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:443
Timer T314 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:444
Timer T316 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:445
Timer T318 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:446
Timer T319 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:447
Timer T320 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:448
Timer T321 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:449
Timer T322 stopped
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]:
U1_CALL_INITIATED ==to==> U0_NULL_STATE
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: call.c:373 PUT
(1 => 0)
Mar 8 18:17:55 VERBOSE[16381] logger.c: q931 visdn0:CALL[75.O]: Freeing call
--
Maurizio Marini GSM +39-335-8259739
Fano: +39-0721-855285 Milano +39-02303123406
S. Costanzo: +39-0721950396 IAXTel: (700) 350-1234
Crashing is the only thing windows does quickly.
_______________________________________________
Visdn-hackers mailing list
[email protected]
https://mailman.uli.it/mailman/listinfo/visdn-hackers
