Menu
▾
▴
Re: [Sofia-sip-devel] User Defined Signal 1
|
From: Jerry R. <je...@to...> - 2007-08-06 14:47:21
|
Hello All,
Here is the sofia-sip stack trace of my application initialization, and an
incoming call. When the far-end sends a BYE, bad things happen.
If you look at the end of the trace logs below, you will notice there is a
"i_error 500 Responding to a Non-Existing Request" log and then shortly
thereafter the User Defined Signal 1 occurs.
Can anyone give me any clues as to what might be the problem here?
Best Regards,
Jerry
su_port_create() called
su_port_create() returns 0x100f3510
nua: nua_create: entering
nua: nua_stack_init: entering
nua: nua_stack_set_params: entering
soa_create("default", 0x100f42e8, 0x100f43b0) called
soa_set_params(static::0x100f4748, ...) called
nta_agent_create: initialized hash tables
nta_agent_create: initialized transports
nta_agent_create: initialized random identifiers
nta_agent_create: initialized timer
nta_agent_create: initialized resolver
tport_create(): 0x100f59d8
nta: master transport created
tport_bind_server(0x100f59d8) to */192.168.72.183:5061/sip
tport_bind_server(0x100f59d8): calling tport_listen for udp
tport_alloc_primary(0x100f59d8): new primary tport 0x100f5dd0
tport_listen(0x100f5dd0): listening at udp/192.168.72.183:5061/sip
tport_bind_server(0x100f59d8): calling tport_listen for tcp
tport_alloc_primary(0x100f59d8): new primary tport 0x100f6068
tport_listen(0x100f6068): listening at tcp/192.168.72.183:5061/sip
nta: bound to (192.168.72.183:5061;transport=*)
nta: agent_init_via: SIP/2.0/udp 192.168.72.183:5061 (sip)
nta: agent_init_via: SIP/2.0/tcp 192.168.72.183:5061 (sip)
nta: Via fields initialized
nta: Contact header created
nua_register: Adding contact URL '192.168.72.183' to list.
nua: nh_create_handle: entering
nua: nua_register: entering
nua: nh_create_handle: enteAlgorithmics/MIPS FPU Emulator v1.5
ring
nua: nua_subscribe: entering
nua(0x100f66e0): signal r_register
nua: nua_stack_set_params: entering
soa_clone(static::0x100f4748, 0x100f42e8, 0x100f66e0) called
soa_set_params(static::0x100f6f10, ...) called
soa_set_params(static::0x100f6f10, ...) called
nua(0x100f66e0): adding register usage
nta_leg_create(0x100f77b0)
nta: selecting scheme sip
tport_tsend(0x100f5dd0) tpn = */192.168.72.130:5060
tport_resolve addrinfo = 192.168.72.130:5060
tport_by_addrinfo(0x100f5dd0): not found by name */192.168.72.130:5060
tport_vsend(0x100f5dd0): 511 bytes of 511 to */192.168.72.130:5060
tport_vsend returned 511
nta: sent REGISTER (9624875) to */192.168.72.130:5060
tport_pend(0x100f5dd0): pending 0x100f7d10 for udp/192.168.72.183:5061
(already 0)
nua(0x100f6ad0): signal r_subscribe
nua: nua_stack_set_params: entering
soa_clone(static::0x100f4748, 0x100f42e8, 0x100f6ad0) called
soa_set_params(static::0x100f8cb8, ...) called
soa_set_params(static::0x100f8cb8, ...) called
nta_leg_create(0x100f94e8)
nua(0x100f6ad0): adding subscribe usage with event message-summary
nta: selecting scheme sip
tport_tsend(0x100f5dd0) tpn = */192.168.72.130:5060
tport_resolve addrinfo = 192.168.72.130:5060
tport_by_addrinfo(0x100f5dd0): not found by name */192.168.72.130:5060
tport_vsend(0x100f5dd0): 590 bytes of 590 to */192.168.72.130:5060
tport_vsend returned 590
nta: sent SUBSCRIBE (9624875) to */192.168.72.130:5060
tport_pend(0x100f5dd0): pending 0x100f9948 for udp/192.168.72.183:5061
(already 1)
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100faa88 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100faa88 (425 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received 100 Trying for REGISTER (9624875)
nta: 100 Trying going to a transaction
nta_outgoing: RTT is tport_release(0x100f5dd0): 0x100f7d10 by 0x100f8638
with 0x100faa88 (preliminary)
nua: nua_stack_event: entering
nua(0x100f66e0): r_register 100 Trying
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100fb100 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100fb100 (504 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received 200 OK for REGISTER (9624875)
nta: 200 OK going to a transaction
tport_release(0x100f5dd0): 0x100f7d10 by 0x100f8638 with 0x100fb100
nua(): refresh register after 77 seconds (in [60..90])
nua: nua_stack_event: entering
nua(0x100f66e0): r_register 200 OK
nua: nua_event: entering
nua: nua_event: entering
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100faa88 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100faa88 (413 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received 200 OK for SUBSCRIBE (9624875)
nta: 200 OK going to a transaction
nta_outgoing: RTT is tport_release(0x100f5dd0): 0x100f9948 by 0x100fa078
with 0x100faa88
nua(): refresh subscribe after 32 seconds (in [32..32])
nua: nua_stack_event: entering
nua(0x100f6ad0): r_subscribe 200 OK
nua: nua_event: entering
dsp_spkStatus(spkStat=0)
nta: timer K fired, terminate REGISTER (9624875)
nta: timer K fired, terminate SUBSCRIBE (9624875)
outgoing_reclaim_all((nil), (nil), 0x7edffb98)
nta_outgoing_timer: 0/0 resent, 0/0 tout, 2/2 term, 2/2 free
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100f7d10 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100f7d10 (794 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received INVITE sip:1028@192.168.72.183:5061 SIP/2.0 (CSeq 102)
nta: canonizing sip:1028@192.168.72.183:5061 with contact
nta: INVITE (102) going to a default leg
nua: nua_stack_process_request: entering
nua: nh_create: entering
nua: nh_create_handle: entering
nua: nua_stack_set_params: entering
soa_clone(static::0x100f4748, 0x100f42e8, 0x100f9948) called
soa_set_params(static::0x100f9c70, ...) called
soa_set_params(static::0x100f9c70, ...) called
nta_leg_create(0x100f9f30)
soa_init_offer_answer(static::0x100f9c70) called
soa_set_remote_sdp(static::0x100f9c70, (nil), 0x100fbcc1, 265) called
nua(0x100f9948): adding session usage
tport_tsend(0x100f5dd0) tpn = UDP/192.168.72.130:5060
tport_resolve addrinfo = 192.168.72.130:5060
tport_by_addrinfo(0x100f5dd0): not found by name UDP/192.168.72.130:5060
tport_vsend(0x100f5dd0): 316 bytes of 316 to UDP/192.168.72.130:5060
tport_vsend returned 316
nta: sent 100 Trying for INVITE (102)
nua: nua_stack_event: entering
nua(0x100f9948): i_invite 100 Trying
nua(0x100f9948): call state changed: init -> received, received offer
soa_get_remote_sdp(static::0x100f9c70, [0x7edff708], [0x7edff70c], [(nil)])
called
nua: nua_stack_event: entering
nua(0x100f9948): i_state 100 Trying
nua: nua_event: entering
nua: nua_respond: entering
nua: nua_respond: entering
DestroySdp==>0x7f1ffaf0
dsp_spkStatus(spkStat=1)
nua: nua_event: entering
me_GetEncoderRate(encoder=0)==>rate=30
nua(0x100f9948): signal r_respond 180 Ringing
nua: respond_to_invite: entering
nua: nua_stack_set_params: entering
soa_set_params(static::0x100f9c70, ...) called
tport_tsend(0x100f5dd0) tpn = UDP/192.168.72.130:5060
tport_resolve addrinfo = 192.168.72.130:5060
tport_by_addrinfo(0x100f5dd0): not found by name UDP/192.168.72.130:5060
tport_vsend(0x100f5dd0): 489 bytes of 489 to UDP/192.168.72.130:5060
tport_vsend returned 489
nta: sent 180 Ringing for INVITE (102)
nua(0x100f9948): call state changed: received -> early
nua: nua_stack_event: entering
nua(0x100f9948): i_state 180 Ringing
nua(0x100f9948): signal r_respond 200 OK
nua: respond_to_invite: entering
nua: nua_stack_set_params: entering
soa_set_params(static::0x100f9c70, ...) called
soa_set_user_sdp(static::0x100f9c70, (nil), 0x100fbf2c, -1) called
soa_set_capability_sdp(static::0x100f9c70, (nil), 0x100fbf2c, -1) called
su_localinfo: if lo with index 1
su_localinfo: if esw with index 2
soa_generate_answer(static::0x100f9c70, (nil)) called
soa_static_offer_answer_action(0x100f9c70, soa_generate_answer): called
soa_static(0x100f9c70, soa_generate_answer): generating local description
su_localinfo: if lo with index 1
su_localinfo: if esw with index 2
soa_static(0x100f9c70, soa_generate_answer): upgrade with remote description
soa_sdp_mode_set_is_needed(0x7edfd9a0, 0x100fa838, ""): called
soa_static(0x100f9c70, soa_generate_answer): storing local description
soa_activate(static::0x100f9c70, (nil)) called
soa_get_local_sdp(static::0x100f9c70, [(nil)], [0x7edffac0], [0x7edffac4])
called
tport_tsend(0x100f5dd0) tpn = UDP/192.168.72.130:5060
tport_resolve addrinfo = 192.168.72.130:5060
tport_by_addrinfo(0x100f5dd0): not found by name UDP/192.168.72.130:5060
tport_vsend(0x100f5dd0): 759 bytes of 759 to UDP/192.168.72.130:5060
tport_vsend returned 759
nta: sent 200 OK for INVITE (102)
nua(0x100f9948): call state changed: early -> completed, sent answer
soa_get_local_sdp(static::0x100f9c70, [0x7edffad8], [0x7edffadc], [(nil)])
called
soa_get_params(static::0x100f9c70, ...) called
nua: nua_stack_event: entering
nua(0x100f9948): i_state 200 OK
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100fbdd8 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100fbdd8 (398 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received ACK sip:192.168.72.183:5061 SIP/2.0 (CSeq 102)
nta: ACK (102) is going to INVITE (102)
nua: process_ack_or_cancel: entering
soa_clear_remote_sdp(static::0x100f9c70) called
nua: nua_stack_event: entering
nua(0x100f9948): i_ack 200 OK
nua(0x100f9948): call state changed: completed -> ready
nua: nua_stack_event: entering
nua(0x100f9948): i_state 200 OK
nua: nua_stack_event: entering
nua(0x100f9948): i_active 200 Call active
nua(): refresh session after 883 seconds (in [450..1350])
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100fda30 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100fda30 (690 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received INVITE sip:192.168.72.183:5061 SIP/2.0 (CSeq 103)
nta: canonizing sip:192.168.72.183:5061 with contact
nta: INVITE (103) going to existing leg
nua: nua_stack_process_request: entering
soa_init_offer_answer(static::0x100f9c70) called
soa_set_remote_sdp(static::0x100f9c70, (nil), 0x100fde11, 185) called
nua: respond_to_invite: entering
nua: nua_stack_set_params: entering
soa_set_params(static::0x100f9c70, ...) called
soa_generate_answer(static::0x100f9c70, (nil)) called
soa_static_offer_answer_action(0x100f9c70, soa_generate_answer): called
soa_static(0x100f9c70, soa_generate_answer): upgrade with remote description
soa_sdp_mode_set_is_needed(0x7edfd4e8, 0x100fec38, ""): called
soa_activate(static::0x100f9c70, (nil)) called
soa_get_local_sdp(static::0x100f9c70, [(nil)], [0x7edff608], [0x7edff60c])
called
tport_tsend(0x100f5dd0) tpn = UDP/192.168.72.130:5060
tport_resolve addrinfo = 192.168.72.130:5060
tport_by_addrinfo(0x100f5dd0): not found by name UDP/192.168.72.130:5060
tport_vsend(0x100f5dd0): 759 bytes of 759 to UDP/192.168.72.130:5060
tport_vsend returned 759
nta: sent 200 OK for INVITE (103)
nua: nua_stack_event: entering
nua(0x100f9948): i_invite 200 OK
nua(0x100f9948): ready call updated: completed received offer, sent answer
soa_get_remote_sdp(static::0x100f9c70, [0x7edff618], [0x7edff61c], [(nil)])
called
soa_get_local_sdp(static::0x100f9c70, [0x7edff620], [0x7edff624], [(nil)])
called
soa_get_params(static::0x100f9c70, ...) called
nua: nua_stack_event: entering
nua(0x100f9948): i_state 200 OK
nua: nua_event: entering
nua: nua_event: entering
nua: nua_event: entering
nua: nua_event: entering
nua: nua_event: entering
nua: nua_ack: entering
nua: nua_event: entering
nua: nua_ack: entering
nua: nua_respond: entering
DestroySdp==>0x7f1ffaf0
nua: nua_event: entering
me_GetEncoderRate(encoder=0)==>rate=30
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100fbdd8 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100fbdd8 (398 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received ACK sip:192.168.72.183:5061 SIP/2.0 (CSeq 103)
nta: ACK (103) is going to INVITE (103)
nua: process_ack_or_cancel: entering
soa_clear_remote_sdp(static::0x100f9c70) called
nua: nua_stack_event: entering
nua(0x100f9948): i_ack 200 OK
nua(0x100f9948): ready call updated: ready
nua: nua_stack_event: entering
nua(0x100f9948): i_state 200 OK
nua: nua_stack_event: entering
nua(0x100f9948): i_active 200 Call active
nua(): refresh session after 732 seconds (in [450..1350])
nua(0x100f9948): signal r_ack
nua: nua_stack_event: entering
nua(0x100f9948): i_error 900 No response to ACK
nua(0x100f9948): signal r_ack
nua: nua_stack_event: entering
nua(0x100f9948): i_error 900 No response to ACK
nua(0x100f9948): signal r_respond 200 OK
nua: nua_stack_event: entering
nua(0x100f9948): i_error 500 Responding to a Non-Existing Request
nua: nua_event: entering
nua: nua_event: entering
nua: nua_event: entering
nua: nua_ack: entering
nua: nua_event: entering
nua: nua_event: entering
nua: nua_event: entering
nua(0x100f9948): signal r_ack
nua: nua_stack_event: entering
nua(0x100f9948): i_error 900 No response to ACK
nua: nua_event: entering
nta: timer I fired, terminate 200 response
incoming_reclaim_all((nil), (nil), 0x7edffb90)
nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
nta: timer I fired, terminate 200 response
incoming_reclaim_all((nil), (nil), 0x7edffb90)
nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100f7d10 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100f7d10 (667 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received INVITE sip:192.168.72.183:5061 SIP/2.0 (CSeq 104)
nta: canonizing sip:192.168.72.183:5061 with contact
nta: INVITE (104) going to existing leg
nua: nua_stack_process_request: entering
soa_init_offer_answer(static::0x100f9c70) called
soa_set_remote_sdp(static::0x100f9c70, (nil), 0x100ff2d1, 162) called
nua: respond_to_invite: entering
nua: nua_stack_set_params: entering
soa_set_params(static::0x100f9c70, ...) called
soa_generate_answer(static::0x100f9c70, (nil)) called
soa_static_offer_answer_action(0x100f9c70, soa_generate_answer): called
soa_static(0x100f9c70, soa_generate_answer): upgrade with remote description
soa_sdp_mode_set_is_needed(0x7edfd4e8, 0x100fa898, ""): called
soa_activate(static::0x100f9c70, (nil)) called
soa_get_local_sdp(static::0x100f9c70, [(nil)], [0x7edff608], [0x7edff60c])
called
tport_tsend(0x100f5dd0) tpn = UDP/192.168.72.130:5060
tport_resolve addrinfo = 192.168.72.130:5060
tport_by_addrinfo(0x100f5dd0): not found by name UDP/192.168.72.130:5060
tport_vsend(0x100f5dd0): 759 bytes of 759 to UDP/192.168.72.130:5060
tport_vsend returned 759
nta: sent 200 OK for INVITE (104)
nua: nua_stack_event: entering
nua(0x100f9948): i_invite 200 OK
nua(0x100f9948): ready call updated: completed received offer, sent answer
soa_get_remote_sdp(static::0x100f9c70, [0x7edff618], [0x7edff61c], [(nil)])
called
soa_get_local_sdp(static::0x100f9c70, [0x7edff620], [0x7edff624], [(nil)])
called
soa_get_params(static::0x100f9c70, ...) called
nua: nua_stack_event: entering
nua(0x100f9948): i_state 200 OK
nua: nua_event: entering
nua: nua_ack: entering
nua: nua_respond: entering
DestroySdp==>0x7f1ffaf0
nua: nua_event: entering
me_GetEncoderRate(encoder=0)==>rate=30
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100fbf78 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100fbf78 (398 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received ACK sip:192.168.72.183:5061 SIP/2.0 (CSeq 104)
nta: ACK (104) is going to INVITE (104)
nua: process_ack_or_cancel: entering
soa_clear_remote_sdp(static::0x100f9c70) called
nua: nua_stack_event: entering
nua(0x100f9948): i_ack 200 OK
nua(0x100f9948): ready call updated: ready
nua: nua_stack_event: entering
nua(0x100f9948): i_state 200 OK
nua: nua_stack_event: entering
nua(0x100f9948): i_active 200 Call active
nua(): refresh session after 1218 seconds (in [450..1350])
tport_wakeup_pri(0x100f5dd0): events IN
tport_recv_event(0x100f5dd0)
tport_recv_iovec(0x100f5dd0) msg 0x100fd228 from (udp/192.168.72.183:5061)
has 65536 bytes, veclen = 1
tport_deliver(0x100f5dd0): msg 0x100fd228 (398 bytes) from
udp/192.168.72.130:5061/sip next=(nil)
nta: received BYE sip:192.168.72.183:5061 SIP/2.0 (CSeq 105)
nta: canonizing sip:192.168.72.183:5061 with contact
nta: BYE (105) going to existing leg
nua: nua_stack_process_request: entering
nua: nua_stack_event: entering
nua(0x100f9948): i_bye 200 OK
tport_tsend(0x100f5dd0) tpn = UDP/192.168.72.130:5060
tport_resolve addrinfo = 192.168.72.130:5060
tport_by_addrinfo(0x100f5dd0): not found by name UDP/192.168.72.130:5060
tport_vsend(0x100f5dd0): 297 bytes of 297 to UDP/192.168.72.130:5060
tport_vsend returned 297
nta: sent 200 OK for BYE (105)
nua(0x100f9948): call state changed: ready -> terminated
nua: nua_stack_event: entering
nua(0x100f9948): i_state 200 Received BYE
nua: nua_stack_event: entering
nua(0x100f9948): i_terminated 200 Received BYE
soa_destroy(static::0x100f9c70) called
nua(0x100f9948): removing session usage
nta_leg_destroy(0x100f9f30)
nua: terminated session 0x100f9948
nua(0x100f9948): signal r_ack
nua: nua_stack_event: entering
nua(0x100f9948): i_error 900 No response to ACK
nua(0x100f9948): signal r_respond 200 OK
nua: nua_stack_event: entering
nua(0x100f9948): i_error 500 Responding to a Non-Existing Request
nua: nua_event: entering
nua: nua_event: entering
nua: nua_event: entering
nua: nua_ack: entering
nua: nua_event: entering
nua: nua_handle_destroy: entering
dsp_spkStatus(spkStat=0)
User defined signal 1
#
#
#
------ Application terminated ------
|
