Hello again,
i tried to call a phone from my mobile.
- the phone rings -- fine
- i aborted the call from my mobile
- my phone rings on and on...
- I tried to refuse the call with my phone but it was still ringing
- finally I tried to answer that call.
Here's the debug from asterisk:
q931 visdn1:TEI[127]: Received message:
q931 <- call reference = 1.I (len 1)
q931 <- message_type = SETUP (5)
q931 visdn1:TEI[0]: DLC autorelease timer stopped
q931 visdn1:CALL[1.I]: call.c:277 GET (1 => 2)
q931 visdn1:CALL[1.I]: Got SETUP
q931 <- SO IE 1 ===> 161 (Sending Complete) length=(1)
q931 <- VL IE 2 ===> 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 3 ===> 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 4 ===> 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 = Network provided (3)
q931 <- Number = 160XXXXXXX
q931 <- VL IE 5 ===> 112 (Called Party Number) length=(7)
q931 <- Type of number = Subscriber (4)
q931 <- Numbering plan = ISDN Telephony (1)
q931 <- Number = 11
q931 <- VL IE 6 ===> 125 (High Layer Compatibility) length=(2)
q931 <- Coding standard = CCITT (0)
q931 <- Characteristics identification = Telephony (1)
q931 visdn1:CALL[1.I]: Requested channel B1 available
q931 visdn1:CALL[1.I]: U0_NULL_STATE ==to==> U6_CALL_PRESENT
q931 visdn1:CALL[1.I]: chan_visdn.c:372 GET (2 => 3)
q931
q931 visdn1:CALL[1.I]: input.c:1210 PUT (3 => 2)
q931 visdn1:TEI[127]: Releasing message
q931 visdn1:TEI[127]: Releasing DLC
q931 visdn1:CALL[1.I]: chan_visdn.c:3229 GET (2 => 3)
q931 visdn1:CALL[1.I]: chan_visdn.c:338 GET (3 => 4)
q931 visdn1:CALL[1.I]: chan_visdn.c:4331 PUT (4 => 3)
q931 visdn1:CALL[1.I]: PROCEEDING-REQ
q931 visdn1:CALL[1.I]: Sending message:
q931 -> message type: CALL PROCEEDING (2)
q931 -> VL IE 0 ===> 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
q931 visdn1:TEI[0]: DLC is disconnected, requesting connection
q931 visdn1:TEI[0]: DLC is awaiting connection: message queued
q931 visdn1:CALL[1.I]: U6_CALL_PRESENT ==to==> U9_INCOMING_CALL_PROCEEDING
q931 visdn1:CALL[1.I]: chan_visdn.c:2234 PUT (3 => 2)
-- Executing Dial("VISDN/visdn1/1.I", "VISDN/visdn2/11") in new stack
q931 visdn2:CALL[121.O]: call.c:311 GET (1 => 2)
q931 visdn2:CALL[121.O]: chan_visdn.c:803 GET (2 => 3)
q931 visdn2:CALL[121.O]: chan_visdn.c:338 GET (3 => 4)
q931 visdn2:CALL[121.O]: chan_visdn.c:938 PUT (4 => 3)
-- Called visdn2/11
q931 visdn2:CALL[121.O]: SETUP-REQ
q931 visdn2[B1]: changed state from AVAILABLE to SELECTED
q931 visdn2:CALL[121.O]: call.c:393 GET (3 => 4)
q931 visdn2:CALL[121.O]: call.c:1838 Timer T303 started
q931 visdn2:CALL[121.O]: call.c:393 GET (4 => 5)
q931 visdn2:CALL[121.O]: call.c:1867 Timer T312 started
q931 visdn2:CALL[121.O]: Sending message:
q931 visdn2:CALL[121.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 = Thu Mar 30 16:00:49 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 = Network provided (3)
q931 -> Number = 160XXXXXXX
q931 -> VL IE 4 ===> 112 (Called Party Number) -- length 7
q931 -> Type of number = Unknown (0)
q931 -> Numbering plan = Unknown (0)
q931 -> Number = 11
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[121.O]: N0_NULL_STATE ==to==> N6_CALL_PRESENT
q931 visdn2:CALL[121.O]: chan_visdn.c:2234 PUT (5 => 4)
q931 visdn1:TEI[0]: DL-ESTABLISH-CONFIRM
q931 visdn1:CALL[1.I]: input.c:57 GET (2 => 3)
q931 visdn1:CALL[1.I]: input.c:69 PUT (3 => 2)
q931 visdn1:TEI[0]: Releasing message
q931 Releasing message
q931 visdn2:TEI[98]: Received message:
q931 <- call reference = 121.O (len 1)
q931 <- message_type = CALL PROCEEDING (2)
q931 visdn2:CALL[121.O]: call.c:421 GET (4 => 5)
q931 visdn2:CALL[121.O]: Got CALL PROCEEDING
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 visdn2:CALL[121.O]: ces.c:92 GET (5 => 6)
q931 visdn2:TEI[98]: DLC autorelease timer stopped
q931 visdn2:CES[98]: CES (TEI=98) allocated for call 121
q931 visdn2:CALL[121.O]: call.c:405 PUT (6 => 5)
q931 visdn2:CALL[121.O]: call.c:3708 Timer T303 stopped
q931 visdn2:CALL[121.O]: call.c:393 GET (5 => 6)
q931 visdn2:CALL[121.O]: call.c:3709 Timer T310 started
q931 visdn2:CALL[121.O]: chan_visdn.c:372 GET (6 => 7)
q931 visdn2:CES[98]: CES-PROCEEDING-REQ
q931 visdn2:CES[98]: I0_NULL_STATE ==to==> I9_INCOMING_CALL_PROCEEDING
q931 visdn2:CALL[121.O]: N6_CALL_PRESENT ==to==> N9_INCOMING_CALL_PROCEEDING
q931
q931 visdn2:CALL[121.O]: input.c:1210 PUT (7 => 6)
q931 visdn2:TEI[98]: Releasing message
q931 visdn2:CALL[121.O]: chan_visdn.c:4331 PUT (6 => 5)
-- VISDN/visdn2/121.O is proceeding passing it to VISDN/visdn1/1.I
q931 visdn2:TEI[98]: Received message:
q931 <- call reference = 121.O (len 1)
q931 <- message_type = ALERTING (1)
q931 visdn2:CALL[121.O]: call.c:421 GET (5 => 6)
q931 visdn2:CES[98]: Got ALERTING
q931 visdn2:CES[98]: I9_INCOMING_CALL_PROCEEDING ==to==> I7_CALL_RECEIVED
q931 visdn2:CALL[121.O]: INT-ALERTING-INDICATION
q931 visdn2:CALL[121.O]: call.c:405 PUT (6 => 5)
q931 visdn2:CALL[121.O]: call.c:2190 Timer T310 stopped
q931 visdn2:CALL[121.O]: call.c:393 GET (5 => 6)
q931 visdn2:CALL[121.O]: call.c:2191 Timer T301 started
q931 visdn2:CALL[121.O]: N9_INCOMING_CALL_PROCEEDING ==to==>
N7_CALL_RECEIVED
q931 visdn2:CALL[121.O]: chan_visdn.c:372 GET (6 => 7)
q931 visdn2:CALL[121.O]: input.c:1210 PUT (7 => 6)
q931 visdn2:TEI[98]: Releasing message
-- VISDN/visdn2/121.O is ringing
q931 visdn1:CALL[1.I]: chan_visdn.c:338 GET (2 => 3)
q931 visdn2:CALL[121.O]: chan_visdn.c:4331 PUT (6 => 5)
q931 visdn1:CALL[1.I]: ALERTING-REQ
q931 visdn1: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 visdn1:TEI[0]: Releasing message
q931 visdn1:CALL[1.I]: U9_INCOMING_CALL_PROCEEDING ==to==> U7_CALL_RECEIVED
q931 visdn1:CALL[1.I]: chan_visdn.c:2234 PUT (3 => 2)
q931 visdn2:TEI[99]: DLC autorelease timer fired
q931 visdn2:TEI[99]: DL-RELEASE-CONFIRM
q931 visdn2:CALL[121.O]: input.c:119 GET (5 => 6)
q931 visdn2:CALL[121.O]: input.c:131 PUT (6 => 5)
q931 Releasing message
q931 visdn2:TEI[99]: DL-ESTABLISH-INDICATION
q931 visdn2:TEI[99]: DLC autorelease timer stopped
q931 visdn2:TEI[99]: DLC autorelease timer started
q931 visdn2:CALL[121.O]: input.c:92 GET (5 => 6)
q931 visdn2:CALL[121.O]: input.c:104 PUT (6 => 5)
q931 Releasing message
q931 visdn2:CALL[121.O]: T312 fired
q931 visdn2:CALL[121.O]: call.c:3257 PUT (5 => 4)
q931 visdn1:TEI[0]: Protocol discriminator 4 not supported, ignoring message
q931 visdn1:TEI[0]: Releasing message
q931 visdn2:TEI[99]: DLC autorelease timer fired
q931 visdn2:TEI[99]: DL-RELEASE-CONFIRM
q931 visdn2:CALL[121.O]: input.c:119 GET (4 => 5)
q931 visdn2:CALL[121.O]: input.c:131 PUT (5 => 4)
q931 Releasing message
q931 visdn2:TEI[99]: DL-ESTABLISH-INDICATION
q931 visdn2:TEI[99]: DLC autorelease timer stopped
q931 visdn2:TEI[99]: DLC autorelease timer started
q931 visdn2:CALL[121.O]: input.c:92 GET (4 => 5)
q931 visdn2:CALL[121.O]: input.c:104 PUT (5 => 4)
q931 Releasing message
q931 visdn1:TEI[0]: Protocol discriminator 4 not supported, ignoring message
q931 visdn1:TEI[0]: Releasing message
q931 visdn2:TEI[99]: DLC autorelease timer fired
q931 visdn2:TEI[99]: DL-RELEASE-CONFIRM
q931 visdn2:CALL[121.O]: input.c:119 GET (4 => 5)
q931 visdn2:CALL[121.O]: input.c:131 PUT (5 => 4)
q931 Releasing message
q931 visdn2:TEI[99]: DL-ESTABLISH-INDICATION
q931 visdn2:TEI[99]: DLC autorelease timer stopped
q931 visdn2:TEI[99]: DLC autorelease timer started
q931 visdn2:CALL[121.O]: input.c:92 GET (4 => 5)
q931 visdn2:CALL[121.O]: input.c:104 PUT (5 => 4)
q931 Releasing message
q931 visdn2:TEI[98]: Received message:
q931 <- call reference = 121.O (len 1)
q931 <- message_type = CONNECT (7)
q931 visdn2:CALL[121.O]: call.c:421 GET (4 => 5)
q931 visdn2:CES[98]: Got CONNECT
q931 <- VL IE 1 ===> 76 (Connected Number) length=(8)
q931 <- Type of number = Unknown (0)
q931 <- Numbering plan = ISDN Telephony (1)
q931 <- Presentation indicator = Presentation allowed (0)
q931 <- Screening indicator = User provided, not screened (0)
q931 <- Number = 11
q931 visdn2:CES[98]: I7_CALL_RECEIVED ==to==> I8_CONNECT_REQUEST
q931 visdn2:CALL[121.O]: INT-CONNECT-INDICATION
q931 visdn2:CALL[121.O]: No channel identification IE, using proposed
channel 0
q931 visdn2:CALL[121.O]: call.c:405 PUT (5 => 4)
q931 visdn2:CALL[121.O]: call.c:2224 Timer T301 stopped
q931 visdn2:CALL[121.O]: N7_CALL_RECEIVED ==to==> N8_CONNECT_REQUEST
q931 visdn2:CALL[121.O]: chan_visdn.c:372 GET (4 => 5)
q931 visdn2:CALL[121.O]: input.c:1210 PUT (5 => 4)
q931 visdn2:TEI[98]: Releasing message
q931 visdn2:CALL[121.O]: chan_visdn.c:338 GET (4 => 5)
-- VISDN/visdn2/121.O answered VISDN/visdn1/1.I
q931 visdn1:CALL[1.I]: chan_visdn.c:338 GET (2 => 3)
-- Attempting native bridge of VISDN/visdn1/1.I and VISDN/visdn2/121.O
q931 visdn2:CALL[121.O]: chan_visdn.c:4331 PUT (5 => 4)
q931 visdn2:CALL[121.O]: SETUP-COMPLETE-REQ
q931 visdn2[B1]: changed state from SELECTED to CONNECTED
q931 visdn2:CALL[121.O]: Sending message:
q931 -> message type: CONNECT ACKNOWLEDGE (15)
q931
q931 visdn2:TEI[98]: Releasing message
q931 visdn2:CES[98]: CES 98 freed for call 121.O
q931 visdn2:CALL[121.O]: ces.c:127 PUT (4 => 3)
q931 visdn2:CALL[121.O]: N8_CONNECT_REQUEST ==to==> N10_ACTIVE
q931 visdn2:CALL[121.O]: chan_visdn.c:2234 PUT (3 => 2)
q931 visdn1:CALL[1.I]: SETUP-RESP
q931 visdn1:CALL[1.I]: Sending message:
q931 -> message type: CONNECT (7)
q931
q931 visdn1:TEI[0]: Releasing message
q931 visdn1:CALL[1.I]: call.c:393 GET (3 => 4)
q931 visdn1:CALL[1.I]: call.c:1971 Timer T313 started
q931 visdn1:CALL[1.I]: U7_CALL_RECEIVED ==to==> U8_CONNECT_REQUEST
q931 visdn1:CALL[1.I]: chan_visdn.c:2234 PUT (4 => 3)
q931 visdn1:TEI[0]: Protocol discriminator 6 not supported, ignoring message
q931 visdn1:TEI[0]: Releasing message
q931 visdn2:TEI[99]: DLC autorelease timer fired
q931 visdn2:TEI[99]: DL-RELEASE-CONFIRM
q931 visdn2:CALL[121.O]: input.c:119 GET (2 => 3)
q931 visdn2:CALL[121.O]: input.c:131 PUT (3 => 2)
q931 Releasing message
q931 visdn2:TEI[99]: DL-ESTABLISH-INDICATION
q931 visdn2:TEI[99]: DLC autorelease timer stopped
q931 visdn2:TEI[99]: DLC autorelease timer started
q931 visdn2:CALL[121.O]: input.c:92 GET (2 => 3)
q931 visdn2:CALL[121.O]: input.c:104 PUT (3 => 2)
q931 Releasing message
q931 visdn1:CALL[1.I]: T313 fired
q931 visdn1:CALL[1.I]: Sending message:
q931 -> message type: DISCONNECT (69)
q931 -> VL IE 0 ===> 8 (Cause) -- length 5
q931 -> Coding standard = CCITT (0)
q931 -> Location = Private network serving remote user (5)
q931 -> Cause value = Recovery on timer expiry (102)
q931
q931 visdn1:TEI[0]: Releasing message
q931 visdn1:CALL[1.I]: call.c:393 GET (3 => 4)
q931 visdn1:CALL[1.I]: call.c:3284 Timer T305 started
q931 visdn1:CALL[1.I]: U8_CONNECT_REQUEST ==to==> U11_DISCONNECT_REQUEST
q931 visdn1:CALL[1.I]: chan_visdn.c:372 GET (4 => 5)
q931 visdn1:CALL[1.I]: call.c:3297 PUT (5 => 4)
q931 visdn1:CALL[1.I]: chan_visdn.c:4331 PUT (4 => 3)
q931 visdn1:TEI[0]: Call reference length of 8 bytes is too big and not
supported (max 4), ignoring frame
q931 visdn1:TEI[0]: Releasing message
q931 visdn1:TEI[0]: DL-RELEASE-INDICATION
q931 visdn1:CALL[1.I]: input.c:144 GET (3 => 4)
q931 visdn1:CALL[1.I]: call.c:432 Timer T301 stopped
q931 visdn1:CALL[1.I]: call.c:433 Timer T302 stopped
q931 visdn1:CALL[1.I]: call.c:434 Timer T303 stopped
q931 visdn1:CALL[1.I]: call.c:435 Timer T304 stopped
q931 visdn1:CALL[1.I]: call.c:405 PUT (4 => 3)
q931 visdn1:CALL[1.I]: call.c:436 Timer T305 stopped
q931 visdn1:CALL[1.I]: call.c:437 Timer T306 stopped
q931 visdn1:CALL[1.I]: call.c:438 Timer T308 stopped
q931 visdn1:CALL[1.I]: call.c:439 Timer T309 stopped
q931 visdn1:CALL[1.I]: call.c:440 Timer T310 stopped
q931 visdn1:CALL[1.I]: call.c:441 Timer T312 stopped
q931 visdn1:CALL[1.I]: call.c:442 Timer T313 stopped
q931 visdn1:CALL[1.I]: call.c:443 Timer T314 stopped
q931 visdn1:CALL[1.I]: call.c:444 Timer T316 stopped
q931 visdn1:CALL[1.I]: call.c:445 Timer T318 stopped
q931 visdn1:CALL[1.I]: call.c:446 Timer T319 stopped
q931 visdn1:CALL[1.I]: call.c:447 Timer T320 stopped
q931 visdn1:CALL[1.I]: call.c:448 Timer T321 stopped
q931 visdn1:CALL[1.I]: call.c:449 Timer T322 stopped
q931 visdn1[B1]: changed state from AVAILABLE to AVAILABLE
q931 visdn1:CALL[1.I]: U11_DISCONNECT_REQUEST ==to==> U0_NULL_STATE
q931 visdn1:CALL[1.I]: call.c:373 PUT (3 => 2)
q931 visdn1:CALL[1.I]: chan_visdn.c:372 GET (2 => 3)
q931 visdn1:CALL[1.I]: input.c:156 PUT (3 => 2)
q931 Releasing message
q931 visdn1:CALL[1.I]: chan_visdn.c:4331 PUT (2 => 1)
q931 visdn2:CALL[121.O]: chan_visdn.c:338 GET (2 => 3)
q931 visdn2:CALL[121.O]: chan_visdn.c:1852 PUT (3 => 2)
q931 visdn2:CALL[121.O]: DISCONNECT-REQ
q931 visdn2:CALL[121.O]: Sending message:
q931 -> message type: DISCONNECT (69)
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 visdn2:TEI[98]: Releasing message
q931 visdn2:CALL[121.O]: call.c:393 GET (2 => 3)
q931 visdn2:CALL[121.O]: call.c:1182 Timer T305 started
q931 visdn2:CALL[121.O]: N10_ACTIVE ==to==> N12_DISCONNECT_INDICATION
q931 visdn2:CALL[121.O]: chan_visdn.c:2234 PUT (3 => 2)
q931 visdn2:TEI[98]: Received message:
q931 <- call reference = 121.O (len 1)
q931 <- message_type = RELEASE (77)
q931 visdn2:CALL[121.O]: call.c:421 GET (2 => 3)
q931 visdn2:CALL[121.O]: Got RELEASE
q931 visdn2:CALL[121.O]: call.c:405 PUT (3 => 2)
q931 visdn2:CALL[121.O]: call.c:4939 Timer T305 stopped
q931 visdn2:CALL[121.O]: call.c:4940 Timer T306 stopped
q931 visdn2[B1]: changed state from DISCONNECTED to AVAILABLE
q931 visdn2:CALL[121.O]: Sending message:
q931 -> message type: RELEASE COMPLETE (90)
q931
q931 visdn2:TEI[98]: Releasing message
q931 visdn2:CALL[121.O]: N12_DISCONNECT_INDICATION ==to==> N0_NULL_STATE
q931 visdn2:CALL[121.O]: call.c:373 PUT (2 => 1)
q931 visdn2:CALL[121.O]: chan_visdn.c:372 GET (1 => 2)
q931
q931 visdn2:CALL[121.O]: input.c:1210 PUT (2 => 1)
q931 visdn2:TEI[98]: Releasing message
q931 visdn2:CALL[121.O]: chan_visdn.c:4331 PUT (1 => 0)
q931 visdn2:CALL[121.O]: Freeing call
q931 visdn2:TEI[98]: DLC autorelease timer started
== Spawn extension (visdn_telco_in, 11, 1) exited non-zero on
'VISDN/visdn1/1.I'
-- Executing Congestion("VISDN/visdn1/1.I", "") in new stack
== Spawn extension (visdn_telco_in, h, 1) exited non-zero on
'VISDN/visdn1/1.I'
q931 visdn1:CALL[1.I]: chan_visdn.c:1852 PUT (1 => 0)
q931 visdn1:CALL[1.I]: Freeing call
and... when i not abort the call with my mobile and answer it with my phone
the connection is not useable... but i'll post that log only if needed....
Daniele, something like bugzilla would be a good thing for submitting suchs
logs and keep everyone informed of the status of some problems, wouldn't it?
Bye,
Philipp
_______________________________________________
Visdn-hackers mailing list
[email protected]
https://mailman.uli.it/mailman/listinfo/visdn-hackers
