[EMAIL PROTECTED] wrote:
>
> No, sorry. That was all I had. I had logging disabled to track down
> a voicemail issue. I'll leave it on as much as I can now, and inform
> you should that happen again.
Doesn't look as if it was the same bug, but asterisk terminated
during a call now. I complicate things this is
Deutsche Telekom ---> vISDN TE + asterisk + vISDN NT ---> ascom phone
So you'll have two vISDN connections to look at. I tried my best to
send only the relevant part, but in the end I decided to send most of
the log, so here it is shortly before the call is picked up:
q931 -> VL IE 3 ===> 108 (Calling Party Number) -- length 12
q931 -> Type of number = National (2)
q931 -> Numbering plan = ISDN Telephony (1)
q931 -> Presentation indicator = Presentation allowed (0)
q931 -> Screening indicator = User provided, verified and passed (1)
q931 -> Number = 0306768786
q931 -> VL IE 4 ===> 112 (Called Party Number) -- length 9
q931 -> Type of number = Unknown (0)
q931 -> Numbering plan = Unknown (0)
q931 -> Number = 46507666
q931 -> VL IE 5 ===> 125 (High Layer Compatibility) -- length 2
q931 -> Coding standard = CCITT (0)
q931 -> Characteristics identification = Telephony (1)
q931 -> SO IE 6 ===> 161 (Sending Complete)
q931 visdn1:CALL[41.O]: N0_NULL_STATE ==to==> N6_CALL_PRESENT
q931 visdn0:TEI[0]: DL-ESTABLISH-CONFIRM
q931 visdn0:TEI[0]: Releasing message
q931 Releasing message
q931 visdn1:CALL[41.O]: chan_visdn.c:882 PUT (5 => 4)
-- Called visdn1/46507666
q931 visdn2:CALL[17.O]: call.c:305 GET (1 => 2)
q931 visdn2:CALL[17.O]: chan_visdn.c:763 GET (2 => 3)
q931 visdn2:CALL[17.O]: SETUP-REQ
q931 visdn2[B1]: changed state from AVAILABLE to SELECTED
q931 visdn2:CALL[17.O]: call.c:387 GET (3 => 4)
q931 visdn2:CALL[17.O]: call.c:1832 Timer T303 started
q931 visdn2:CALL[17.O]: call.c:387 GET (4 => 5)
q931 visdn2:CALL[17.O]: call.c:1861 Timer T312 started
q931 visdn2:CALL[17.O]: Sending message:
q931 visdn2:CALL[17.O]: -> message type: SETUP (5)
q931 -> VL IE 0 ===> 4 (Bearer Capability) -- length 3
q931 -> Coding Standard = CCITT (0)
q931 -> Information Transfer Capability = Speech (0)
q931 -> Transfer mode = Circuit (0)
q931 -> Information Transfer Rate = 64 kbps (16)
q931 -> User information layer 1 protocol = g.711 a-law (3)
q931 -> VL IE 1 ===> 24 (Channel Identification) -- length 1
q931 -> Interface id = Implicit (0)
q931 -> Interface type = Basic (0)
q931 -> Pref/Excl = Exclusive (1)
q931 -> D channel ident = Is not D channel (0)
q931 -> Coding standard = CCITT (0)
q931 -> Channels = B1
q931 -> VL IE 2 ===> 41 (Date Time) -- length 6
q931 -> DateTime = Sat Feb 25 08:55:08 2006
q931 -> VL IE 3 ===> 108 (Calling Party Number) -- length 12
q931 -> Type of number = National (2)
q931 -> Numbering plan = ISDN Telephony (1)
q931 -> Presentation indicator = Presentation allowed (0)
q931 -> Screening indicator = User provided, verified and passed (1)
q931 -> Number = 0306768786
q931 -> VL IE 4 ===> 112 (Called Party Number) -- length 9
q931 -> Type of number = Unknown (0)
q931 -> Numbering plan = Unknown (0)
q931 -> Number = 46507666
q931 -> VL IE 5 ===> 125 (High Layer Compatibility) -- length 2
q931 -> Coding standard = CCITT (0)
q931 -> Characteristics identification = Telephony (1)
q931 -> SO IE 6 ===> 161 (Sending Complete)
q931 visdn2:CALL[17.O]: N0_NULL_STATE ==to==> N6_CALL_PRESENT
q931 visdn2:CALL[17.O]: chan_visdn.c:882 PUT (5 => 4)
-- Called visdn2/46507666
q931 visdn2:TEI[67]: DL-ESTABLISH-INDICATION
q931 visdn2:TEI[67]: DLC autorelease timer stopped
q931 visdn2:TEI[67]: DLC autorelease timer started
q931 Releasing message
q931 visdn2:TEI[67]: Received message:
q931 <- call reference = 17.O (len 1)
q931 <- message_type = ALERTING (1)
q931 visdn2:CALL[17.O]: call.c:415 GET (4 => 5)
q931 visdn2:CALL[17.O]: Got ALERTING
q931 visdn2:CALL[17.O]: ces.c:92 GET (5 => 6)
q931 visdn2:TEI[67]: DLC autorelease timer stopped
q931 visdn2:CES[67]: CES (TEI=67) allocated for call 17
q931 visdn2:CALL[17.O]: No channel identification IE, using proposed
channel 0
q931 visdn2:CALL[17.O]: call.c:399 PUT (6 => 5)
q931 visdn2:CALL[17.O]: call.c:3468 Timer T303 stopped
q931 visdn2:CALL[17.O]: call.c:387 GET (5 => 6)
q931 visdn2:CALL[17.O]: call.c:3469 Timer T301 started
q931 visdn2:CALL[17.O]: N6_CALL_PRESENT ==to==> N7_CALL_RECEIVED
q931 visdn2:CES[67]: CES-ALERTING-REQ
q931 visdn2:CES[67]: I0_NULL_STATE ==to==> I7_CALL_RECEIVED
q931
q931 visdn2:CALL[17.O]: input.c:1178 PUT (6 => 5)
q931 visdn2:TEI[67]: Releasing message
-- VISDN/visdn2/17.O is ringing
q931 visdn0:CALL[1.I]: ALERTING-REQ
q931 visdn0:CALL[1.I]: Sending message:
q931 -> message type: ALERTING (1)
q931 -> VL IE 0 ===> 30 (Progress Indicator) -- length 2
q931 -> Coding standard = CCITT (0)
q931 -> Location = Private network serving remote user (5)
q931 -> Description = In-band information or appropriate pattern now
available (8)
q931
q931 visdn0:TEI[0]: Releasing message
q931 visdn0:CALL[1.I]: U9_INCOMING_CALL_PROCEEDING ==to==>
U7_CALL_RECEIVED
q931 visdn1:TEI[105]: DL-ESTABLISH-INDICATION
q931 visdn1:TEI[105]: DLC autorelease timer stopped
q931 visdn1:TEI[105]: DLC autorelease timer started
q931 Releasing message
q931 visdn1:TEI[105]: Received message:
q931 <- call reference = 41.O (len 1)
q931 <- message_type = CALL PROCEEDING (2)
q931 visdn1:CALL[41.O]: call.c:415 GET (4 => 5)
q931 visdn1:CALL[41.O]: Got CALL PROCEEDING
q931 visdn1:CALL[41.O]: ces.c:92 GET (5 => 6)
q931 visdn1:TEI[105]: DLC autorelease timer stopped
q931 visdn1:CES[105]: CES (TEI=105) allocated for call 41
q931 visdn1:CALL[41.O]: No channel identification IE, using proposed
channel 0
q931 visdn1:CALL[41.O]: call.c:399 PUT (6 => 5)
q931 visdn1:CALL[41.O]: call.c:3698 Timer T303 stopped
q931 visdn1:CALL[41.O]: call.c:387 GET (5 => 6)
q931 visdn1:CALL[41.O]: call.c:3699 Timer T310 started
q931 visdn1:CES[105]: CES-PROCEEDING-REQ
q931 visdn1:CES[105]: I0_NULL_STATE ==to==> I9_INCOMING_CALL_PROCEEDING
q931 visdn1:CALL[41.O]: N6_CALL_PRESENT ==to==>
N9_INCOMING_CALL_PROCEEDING
q931
q931 visdn1:CALL[41.O]: input.c:1178 PUT (6 => 5)
q931 visdn1:TEI[105]: Releasing message
-- VISDN/visdn1/41.O is proceeding passing it to VISDN/visdn0/1.I
q931 visdn2:TEI[68]: DL-ESTABLISH-INDICATION
q931 visdn2:TEI[68]: DLC autorelease timer stopped
q931 visdn2:TEI[68]: DLC autorelease timer started
q931 Releasing message
q931 visdn2:TEI[68]: Received message:
q931 <- call reference = 17.O (len 1)
q931 <- message_type = ALERTING (1)
q931 visdn2:CALL[17.O]: call.c:415 GET (5 => 6)
q931 visdn2:CALL[17.O]: Got ALERTING
q931 visdn2:CALL[17.O]: ces.c:92 GET (6 => 7)
q931 visdn2:TEI[68]: DLC autorelease timer stopped
q931 visdn2:CES[68]: CES (TEI=68) allocated for call 17
q931 visdn2:CALL[17.O]: No channel identification IE, using proposed
channel 0
q931 visdn2:CES[68]: CES-ALERTING-REQ
q931 visdn2:CES[68]: I0_NULL_STATE ==to==> I7_CALL_RECEIVED
q931
q931 visdn2:CALL[17.O]: input.c:1178 PUT (7 => 6)
q931 visdn2:TEI[68]: Releasing message
q931 visdn1:TEI[105]: Received message:
q931 <- call reference = 41.O (len 1)
q931 <- message_type = ALERTING (1)
q931 visdn1:CALL[41.O]: call.c:415 GET (5 => 6)
q931 visdn1:CES[105]: Got ALERTING
q931 visdn1:CES[105]: I9_INCOMING_CALL_PROCEEDING ==to==>
I7_CALL_RECEIVED
q931 visdn1:CALL[41.O]: INT-ALERTING-INDICATION
q931 visdn1:CALL[41.O]: call.c:399 PUT (6 => 5)
q931 visdn1:CALL[41.O]: call.c:2184 Timer T310 stopped
q931 visdn1:CALL[41.O]: call.c:387 GET (5 => 6)
q931 visdn1:CALL[41.O]: call.c:2185 Timer T301 started
q931 visdn1:CALL[41.O]: N9_INCOMING_CALL_PROCEEDING ==to==>
N7_CALL_RECEIVED
q931 visdn1:CALL[41.O]: input.c:1178 PUT (6 => 5)
q931 visdn1:TEI[105]: Releasing message
-- VISDN/visdn1/41.O is ringing
q931 visdn1:CALL[41.O]: T312 fired
q931 visdn1:CALL[41.O]: call.c:3247 PUT (5 => 4)
q931 visdn2:CALL[17.O]: T312 fired
q931 visdn2:CALL[17.O]: call.c:3247 PUT (6 => 5)
q931 visdn2:TEI[68]: Received message:
q931 <- call reference = 17.O (len 1)
q931 <- message_type = CONNECT (7)
q931 visdn2:CALL[17.O]: call.c:415 GET (5 => 6)
q931 visdn2:CES[68]: Got CONNECT
q931 <- VL IE 1 ===> 76 (Connected Number) length=(10)
q931 <- Type of number = Unknown (0)
q931 <- Numbering plan = Unknown (0)
q931 <- Presentation indicator = Presentation allowed (0)
q931 <- Screening indicator = User provided, not screened (0)
q931 <- Number = 46507666
q931 visdn2:CES[68]: I7_CALL_RECEIVED ==to==> I8_CONNECT_REQUEST
q931 visdn2:CALL[17.O]: INT-CONNECT-INDICATION
q931 visdn2:CALL[17.O]: No channel identification IE, using proposed
channel 0
q931 visdn2:CALL[17.O]: call.c:399 PUT (6 => 5)
q931 visdn2:CALL[17.O]: call.c:2218 Timer T301 stopped
q931 visdn2:CALL[17.O]: N7_CALL_RECEIVED ==to==> N8_CONNECT_REQUEST
q931 visdn2:CALL[17.O]: input.c:1178 PUT (5 => 4)
q931 visdn2:TEI[68]: Releasing message
-- VISDN/visdn2/17.O answered VISDN/visdn0/1.I
q931 visdn1:CALL[41.O]: chan_visdn.c:1782 PUT (4 => 3)
-- Attempting native bridge of VISDN/visdn0/1.I and VISDN/visdn2/17.O
q931 visdn2:CALL[17.O]: SETUP-COMPLETE-REQ
q931 visdn2[B1]: changed state from SELECTED to CONNECTED
q931 visdn2:CALL[17.O]: Sending message:
q931 -> message type: CONNECT ACKNOWLEDGE (15)
q931
q931 visdn2:TEI[68]: Releasing message
q931 visdn2:CES[68]: CES 68 freed for call 17.O
q931 visdn2:CALL[17.O]: ces.c:127 PUT (4 => 3)
q931 visdn2:CES[67]: CES-RELEASE-REQ
q931 visdn2:CES[67]: ces.c:393 Timer T308 started
q931 visdn2:CALL[17.O]: Sending message:
q931 -> message type: RELEASE (77)
q931 -> VL IE 0 ===> 8 (Cause) -- length 2
q931 -> Coding standard = CCITT (0)
q931 -> Location = Private network serving remote user (5)
q931 -> Cause value = Non-selected user clearing (26)
q931
q931 visdn2:TEI[67]: Releasing message
q931 visdn2:CES[67]: I7_CALL_RECEIVED ==to==> I19_RELEASE_REQUEST
q931 visdn2:CALL[17.O]: N8_CONNECT_REQUEST ==to==> N10_ACTIVE
q931 visdn1:CALL[41.O]: DISCONNECT-REQ
q931 visdn1:CALL[41.O]: call.c:399 PUT (3 => 2)
q931 visdn1:CALL[41.O]: call.c:1058 Timer T301 stopped
q931 visdn1[B1]: changed state from SELECTED to AVAILABLE
q931 visdn1:CES[105]: CES-RELEASE-REQ
q931 visdn1:CES[105]: ces.c:393 Timer T308 started
q931 visdn1:CALL[41.O]: Sending message:
q931 -> message type: RELEASE (77)
q931 -> VL IE 0 ===> 8 (Cause) -- length 2
q931 -> Coding standard = CCITT (0)
q931 -> Location = Private network serving remote user (5)
q931 -> Cause value = Normal call clearing (16)
q931
q931 visdn1:TEI[105]: Releasing message
q931 visdn1:CES[105]: I7_CALL_RECEIVED ==to==> I19_RELEASE_REQUEST
q931 visdn1:CALL[41.O]: N7_CALL_RECEIVED ==to==> N0_NULL_STATE
q931 visdn1:CALL[41.O]: call.c:367 PUT (2 => 1)
q931 visdn0:CALL[1.I]: SETUP-RESP
q931 visdn0:CALL[1.I]: Sending message:
q931 -> message type: CONNECT (7)
q931
q931 visdn0:TEI[0]: Releasing message
q931 visdn0:CALL[1.I]: call.c:387 GET (2 => 3)
q931 visdn0:CALL[1.I]: call.c:1965 Timer T313 started
q931 visdn0:CALL[1.I]: U7_CALL_RECEIVED ==to==> U8_CONNECT_REQUEST
q931 visdn0:CALL[1.I]: SETUP-COMPLETE-REQ
Feb 25 08:55:25 WARNING[21446]: libq931:0 : visdn0:CALL[1.I]: Unexpected
q931_setup_complete_request in state U8_CONNECT_REQUEST
q931 visdn2:TEI[67]: Received message:
q931 <- call reference = 17.O (len 1)
q931 <- message_type = RELEASE COMPLETE (90)
q931 visdn2:CALL[17.O]: call.c:415 GET (3 => 4)
q931 visdn2:CES[67]: Got RELEASE COMPLETE
q931 <- VL IE 1 ===> 8 (Cause) length=(2)
q931 <- Coding standard = CCITT (0)
q931 <- Location = User (0)
q931 <- Cause value = Non-selected user clearing (26)
q931 visdn2:CES[67]: ces.c:714 Timer T308 stopped
q931 visdn2:CALL[17.O]: INT-RELEASE-COMPLETE-IND
q931 visdn2:CES[67]: CES 67 freed for call 17.O
q931 visdn2:TEI[67]: DLC autorelease timer started
q931 visdn2:CALL[17.O]: ces.c:127 PUT (4 => 3)
q931 visdn2:CALL[17.O]: input.c:1178 PUT (3 => 2)
q931 visdn2:TEI[67]: Releasing message
q931 visdn1:TEI[105]: Received message:
q931 <- call reference = 41.O (len 1)
q931 <- message_type = RELEASE COMPLETE (90)
q931 visdn1:CALL[41.O]: call.c:415 GET (1 => 2)
q931 visdn1:CES[105]: Got RELEASE COMPLETE
q931 visdn1:CES[105]: ces.c:714 Timer T308 stopped
q931 visdn1:CALL[41.O]: INT-RELEASE-COMPLETE-IND
q931 visdn1:CES[105]: CES 105 freed for call 41.O
q931 visdn1:TEI[105]: DLC autorelease timer started
q931 visdn1:CALL[41.O]: ces.c:127 PUT (2 => 1)
q931 visdn1:CALL[41.O]: input.c:1178 PUT (1 => 0)
q931 visdn1:CALL[41.O]: Freeing call
q931 visdn1:TEI[105]: Releasing message
q931 visdn0:TEI[0]: Received message:
q931 <- call reference = 1.I (len 1)
q931 <- message_type = CONNECT ACKNOWLEDGE (15)
q931 visdn0:CALL[1.I]: call.c:415 GET (3 => 4)
q931 visdn0:CALL[1.I]: Got CONNECT ACKNOWLEDGE
q931 visdn0:CALL[1.I]: call.c:399 PUT (4 => 3)
q931 visdn0:CALL[1.I]: call.c:4161 Timer T313 stopped
q931 visdn0[B1]: changed state from AVAILABLE to CONNECTED
q931 visdn0:CALL[1.I]: U8_CONNECT_REQUEST ==to==> U10_ACTIVE
q931
q931 visdn0:CALL[1.I]: input.c:1178 PUT (3 => 2)
q931 visdn0:TEI[0]: Releasing message
q931 visdn1:TEI[105]: DL-RELEASE-INDICATION
q931 Releasing message
q931 visdn2:TEI[67]: DLC autorelease timer fired
q931 visdn1:TEI[105]: DLC autorelease timer fired
q931 visdn1:TEI[105]: DL-RELEASE-CONFIRM
q931 Releasing message
q931 visdn2:TEI[67]: DL-RELEASE-CONFIRM
q931 Releasing message
q931 visdn0:TEI[0]: Received message:
q931 <- call reference = 1.I (len 1)
q931 <- message_type = DISCONNECT (69)
q931 visdn0:CALL[1.I]: call.c:415 GET (2 => 3)
q931 visdn0:CALL[1.I]: Got DISCONNECT
q931 <- VL IE 1 ===> 8 (Cause) length=(2)
q931 <- Coding standard = CCITT (0)
q931 <- Location = User (0)
q931 <- Cause value = Normal call clearing (16)
q931 <- VL IE 2 ===> 30 (Progress Indicator) length=(2)
q931 <- Coding standard = CCITT (0)
q931 <- Location = Public network serving local user (2)
q931 <- Description = In-band information or appropriate pattern now
available (8)
q931 visdn0:CALL[1.I]: U10_ACTIVE ==to==> U12_DISCONNECT_INDICATION
q931
q931 visdn0:CALL[1.I]: input.c:1178 PUT (3 => 2)
q931 visdn0:TEI[0]: Releasing message
-- Attempting native bridge of VISDN/visdn0/1.I and
VISDN/visdn2/17.O
-- Attempting native bridge of VISDN/visdn0/1.I and
VISDN/visdn2/17.O
q931 visdn2:TEI[68]: DL-RELEASE-INDICATION
q931 visdn2:CALL[17.O]: call.c:387 GET (2 => 3)
q931 visdn2:CALL[17.O]: call.c:6268 Timer T309 started
Feb 25 08:59:12 ERROR[21446]: libq931:0 : visdn2:CALL[17.O]: connect:
Connection timed out
q931 Releasing message
q931 visdn2:CALL[17.O]: T309 fired
q931 visdn2[B1]: changed state from CONNECTED to AVAILABLE
q931 visdn2:CALL[17.O]: N10_ACTIVE ==to==> N0_NULL_STATE
q931 visdn2:CALL[17.O]: call.c:367 PUT (3 => 2)
q931 visdn2:CALL[17.O]: call.c:3073 PUT (2 => 1)
q931 visdn0:TEI[0]: Received message:
q931 <- call reference = 1.I (len 1)
q931 <- message_type = RELEASE (77)
q931 visdn0:CALL[1.I]: call.c:415 GET (2 => 3)
q931 visdn0:CALL[1.I]: Got RELEASE
q931 <- VL IE 1 ===> 8 (Cause) length=(2)
q931 <- Coding standard = CCITT (0)
q931 <- Location = User (0)
q931 <- Cause value = Normal call clearing (16)
q931 visdn0[B1]: changed state from CONNECTED to AVAILABLE
q931 visdn0:CALL[1.I]: Sending message:
q931 -> message type: RELEASE COMPLETE (90)
q931
q931 visdn0:TEI[0]: Releasing message
q931 visdn0:CALL[1.I]: U12_DISCONNECT_INDICATION ==to==> U0_NULL_STATE
q931 visdn0:CALL[1.I]: call.c:367 PUT (3 => 2)
q931
q931 visdn0:CALL[1.I]: input.c:1178 PUT (2 => 1)
q931 visdn0:TEI[0]: Releasing message
q931 visdn2:TEI[68]: DL-RELEASE-INDICATION
q931 Releasing message
q931 visdn0:TEI[0]: Received message:
q931 <- call reference = 1.I (len 1)
q931 <- message_type = RELEASE (77)
q931 visdn0:CALL[1.I]: call.c:415 GET (1 => 2)
q931 visdn0:CALL[1.I]: Got RELEASE
q931 visdn0:CALL[1.I]: Sending message:
q931 -> message type: RELEASE COMPLETE (90)
q931 -> VL IE 0 ===> 8 (Cause) -- length 2
q931 -> Coding standard = CCITT (0)
q931 -> Location = Private network serving remote user (5)
q931 -> Cause value = Invalid call reference value (81)
q931
q931 visdn0:TEI[0]: Releasing message
q931 visdn0:CALL[1.I]: call.c:367 PUT (2 => 1)
q931
q931 visdn0:CALL[1.I]: input.c:1178 PUT (1 => 0)
q931 visdn0:CALL[1.I]: Freeing call
q931 visdn0:TEI[0]: DLC autorelease timer started
q931 visdn0:TEI[0]: Releasing message
q931 visdn0:TEI[0]: DL-RELEASE-INDICATION
q931 Releasing message
q931 visdn2:CALL[17.O]: chan_visdn.c:1782 PUT (1 => 0)
q931 visdn2:CALL[17.O]: Freeing call
q931 visdn2:TEI[68]: DLC autorelease timer started
cave*CLI>
Disconnected from Asterisk server
Executing last minute cleanups
tobias
_______________________________________________
Visdn-hackers mailing list
[email protected]
https://mailman.uli.it/mailman/listinfo/visdn-hackers
