rendered paste body2006-09-29 03:01:50 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'conference'
2006-09-29 03:01:50 [NOTICE] mod_sofia.c:2707 config_sofia() Started Profile test [sip:66.39.160.98:5060]
2006-09-29 03:01:50 [NOTICE] switch_loadable_module.c:99 switch_loadable_module_process() Adding Endpoint 'sofia'
freeswitch@odin.zonedzero.net> 2006-09-29 03:01:50 [DEBUG] mod_sofia.c:2308 event_callback() channel [null] event [nua_r_set_params] status [200] [OK]
freeswitch@odin.zonedzero.net> originate sofia/test/5413899211@66.39.160.3 vox XML default BLAH 5413239321 60
2006-09-29 03:01:52 [NOTICE] switch_channel.c:338 switch_channel_set_name() New Chan sofia/test/5413899211@66.39.160.3 [58f9e587-a14f-db11-b4f6-000c76fb0db6]
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:1444 sofia_outgoing_channel() sofia/test/5413899211@66.39.160.3 State Change CS_NEW -> CS_INIT
2006-09-29 03:01:52 [DEBUG] switch_core.c:2673 switch_core_session_run() (sofia/test/5413899211@66.39.160.3) State INIT
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:718 sofia_on_init() SOFIA INIT
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:640 do_register() SOFIA REGISTER
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:730 sofia_on_init() sofia/test/5413899211@66.39.160.3 State Change CS_INIT -> CS_RING
2006-09-29 03:01:52 [DEBUG] switch_core.c:2709 switch_core_session_run() (sofia/test/5413899211@66.39.160.3) State RING
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:746 sofia_on_ring() SOFIA RING
2006-09-29 03:01:52 [DEBUG] switch_core.c:2396 switch_core_standard_on_ring() Standard RING sofia/test/5413899211@66.39.160.3
2006-09-29 03:01:52 [INFO] switch_core.c:2408 switch_core_standard_on_ring() No Dialplan, changing state to HOLD
2006-09-29 03:01:52 [DEBUG] switch_core.c:2409 switch_core_standard_on_ring() sofia/test/5413899211@66.39.160.3 State Change CS_RING -> CS_HOLD
2006-09-29 03:01:52 [DEBUG] switch_core.c:2854 switch_core_session_run() (sofia/test/5413899211@66.39.160.3) State HOLD
2006-09-29 03:01:52 [DEBUG] switch_core.c:2497 switch_core_standard_on_hold() Standard HOLD
nta_leg_create(0x8232d80)
nta: selecting scheme sip
send 555 bytes to udp/[66.39.160.3]:5060 at 10:01:52.591114:
------------------------------------------------------------------------
REGISTER sip:66.39.160.3:5060 SIP/2.0
Via: SIP/2.0/UDP 66.39.160.98;rport;branch=z9hG4bKNgaQBUZQgZ9vm
Max-Forwards: 70
From: <sip:5413239321@66.39.160.98>;tag=Nac2Q2Z0t7g6D
To: <sip:5413239321@66.39.160.3>
Call-ID: 5f44910d-ca44-1229-1dbe-2d7553ff737c
CSeq: 73643721 REGISTER
Contact: <sip:5413239321@66.39.160.98>
Contact: <sip:5413239321@66.39.160.98>
User-Agent: sofia-sip/1.12.2
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER
Supported: 100rel, precondition, path
Content-Length: 0
------------------------------------------------------------------------
nta: sent REGISTER (73643721) to */66.39.160.3:5060
recv 634 bytes from udp/[66.39.160.3]:5060 at 10:01:52.625368:
------------------------------------------------------------------------
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 66.39.160.98;rport=5060;branch=z9hG4bKNgaQBUZQgZ9vm
From: <sip:5413239321@66.39.160.98>;tag=Nac2Q2Z0t7g6D
To: <sip:5413239321@66.39.160.3>;tag=56eb269c75cbd0d414a0bcf7b30317e8.07d8
Call-ID: 5f44910d-ca44-1229-1dbe-2d7553ff737c
CSeq: 73643721 REGISTER
WWW-Authenticate: Digest realm="66.39.160.3", nonce="451cf070357a86281986587bf67aa563e56c9c41"
Server: OpenSer (1.0.1 (x86_64/openbsd))
Content-Length: 0
Warning: 392 66.39.160.3:5060 "Noisy feedback tells: pid=15222 req_src_ip=66.39.160.98 req_src_port=5060 in_uri=sip:66.39.160.3:5060 out_uri=sip:66.39.160.3:5060 via_cnt==1"
------------------------------------------------------------------------
nta: received 401 Unauthorized for REGISTER (73643721)
nta: 401 Unauthorized going to a transaction
nta_outgoing: RTT is 34.703 ms
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2308 event_callback() channel [sofia/test/5413899211@66.39.160.3] event [nua_r_register] status [401] [Unauthorized]
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2168 sip_r_register() SIP R REGISTER
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2172 sip_r_register() SIP REGISTER UNAUTHORIZED, SENDING: 401
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2176 sip_r_register() Proxy Authentication www-'', proxy-'[Ã1L'.
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2191 sip_r_register() Authenticating 'FreeSWITCH' with '.....'.
nta: selecting scheme sip
send 759 bytes to udp/[66.39.160.3]:5060 at 10:01:52.626983:
------------------------------------------------------------------------
REGISTER sip:66.39.160.3:5060 SIP/2.0
Via: SIP/2.0/UDP 66.39.160.98;rport;branch=z9hG4bKpS3FDpgUD8ZFg
Max-Forwards: 70
From: <sip:5413239321@66.39.160.98>;tag=Nac2Q2Z0t7g6D
To: <sip:5413239321@66.39.160.3>
Call-ID: 5f44910d-ca44-1229-1dbe-2d7553ff737c
CSeq: 73643722 REGISTER
Contact: <sip:5413239321@66.39.160.98>
Contact: <sip:5413239321@66.39.160.98>
User-Agent: sofia-sip/1.12.2
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER
Supported: 100rel, precondition, path
Authorization: Digest username="5413239321", realm="66.39.160.3", nonce="451cf070357a86281986587bf67aa563e56c9c41", algorithm=MD5, uri="sip:66.39.160.3:5060", response="b8fcf056f7084ac2a463588e3fb0997c"
Content-Length: 0
------------------------------------------------------------------------
nta: sent REGISTER (73643722) to */66.39.160.3:5060
recv 630 bytes from udp/[66.39.160.3]:5060 at 10:01:52.664252:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 66.39.160.98;rport=5060;branch=z9hG4bKpS3FDpgUD8ZFg
From: <sip:5413239321@66.39.160.98>;tag=Nac2Q2Z0t7g6D
To: <sip:5413239321@66.39.160.3>;tag=56eb269c75cbd0d414a0bcf7b30317e8.46b2
Call-ID: 5f44910d-ca44-1229-1dbe-2d7553ff737c
CSeq: 73643722 REGISTER
Contact: <sip:5413239321@66.39.160.98>;expires=3600, <sip:5413239321@66.39.160.98:5060>;expires=3600
Server: OpenSer (1.0.1 (x86_64/openbsd))
Content-Length: 0
Warning: 392 66.39.160.3:5060 "Noisy feedback tells: pid=15222 req_src_ip=66.39.160.98 req_src_port=5060 in_uri=sip:66.39.160.3:5060 out_uri=sip:66.39.160.3:5060 via_cnt==1"
------------------------------------------------------------------------
nta: received 200 OK for REGISTER (73643722)
nta: 200 OK going to a transaction
nta_outgoing: RTT is 37.707 ms
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2308 event_callback() channel [sofia/test/5413899211@66.39.160.3] event [nua_r_register] status [200] [OK]
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2168 sip_r_register() SIP R REGISTER
su_localinfo: SIOCGIFINDEX failed: Device not configured
su_localinfo: SIOCGIFINDEX failed: Device not configured
su_localinfo: SIOCGIFINDEX failed: Device not configured
su_localinfo: SIOCGIFINDEX failed: Device not configured
nta_leg_create(0x8258500)
su_localinfo: SIOCGIFINDEX failed: Device not configured
su_localinfo: SIOCGIFINDEX failed: Device not configured
nta_leg_destroy(0x8258500)
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2308 event_callback() channel [sofia/test/5413899211@66.39.160.3] event [nua_r_invite] status [900] [Internal NUA Error]
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2231 sip_r_invite() event AUTHORIZE scheme [Digest], realm [5413239321], username [xxxx], pass [zzz]
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2308 event_callback() channel [sofia/test/5413899211@66.39.160.3] event [nua_i_state] status [900] [Internal NUA Error]
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:1595 sip_i_state() Channel sofia/test/5413899211@66.39.160.3 entering state [terminated]
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:569 terminate_session() Term called from line: 1734
2006-09-29 03:01:52 [NOTICE] mod_sofia.c:579 terminate_session() Hangup sofia/test/5413899211@66.39.160.3 [CS_HOLD] [NORMAL_UNSPECIFIED]
2006-09-29 03:01:52 [INFO] switch_channel.c:907 switch_channel_perform_hangup() Kill sofia/test/5413899211@66.39.160.3 [1]
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:2308 event_callback() channel [sofia/test/5413899211@66.39.160.3] event [nua_i_terminated] status [900] [Internal NUA Error]
2006-09-29 03:01:52 [DEBUG] switch_core.c:2635 switch_core_session_run() (sofia/test/5413899211@66.39.160.3) State HANGUP
2006-09-29 03:01:52 [DEBUG] mod_sofia.c:839 sofia_on_hangup() Channel sofia/test/5413899211@66.39.160.3 hanging up, cause: NORMAL_UNSPECIFIED
2006-09-29 03:01:52 [DEBUG] mod_ivrtest.c:225 my_on_hangup() I globally hooked to [sofia/test/5413899211@66.39.160.3] on the hangup event
2006-09-29 03:01:52 [DEBUG] switch_core.c:2386 switch_core_standard_on_hangup() Standard HANGUP sofia/test/5413899211@66.39.160.3, cause: NORMAL_UNSPECIFIED
2006-09-29 03:01:52 [DEBUG] switch_core.c:3043 switch_core_session_thread() Session 1 (sofia/test/5413899211@66.39.160.3) Locked, Waiting on external entities
API CALL [originate(sofia/test/5413899211@66.39.160.3)] output:
Cannot Create Outgoing Channel! [sofia/test/5413899211@66.39.160.3]
freeswitch@odin.zonedzero.net> 2006-09-29 03:01:52 [DEBUG] switch_ivr.c:2023 switch_ivr_originate() Originate Resulted in Error Cause: 31 [NORMAL_UNSPECIFIED]
2006-09-29 03:01:52 [INFO] switch_core.c:3050 switch_core_session_thread() Session 1 (sofia/test/5413899211@66.39.160.3) Ended
2006-09-29 03:01:52 [NOTICE] switch_core.c:2923 switch_core_session_destroy() Close Channel sofia/test/5413899211@66.39.160.3
nta: timer K fired, terminate REGISTER (73643721)
outgoing_reclaim_all(0x0, 0x0, 0xbf474e60)
nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
nta: timer K fired, terminate REGISTER (73643722)
outgoing_reclaim_all(0x0, 0x0, 0xbf474e60)
nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
originate sofia/test/5413899211@66.39.160.3 vox XML default BLAH 5413239321 60
2006-09-29 03:03:49 [NOTICE] switch_channel.c:338 switch_channel_set_name() New Chan sofia/test/5413899211@66.39.160.3 [ae1e57cd-a14f-db11-b4f6-000c76fb0db6]
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:1444 sofia_outgoing_channel() sofia/test/5413899211@66.39.160.3 State Change CS_NEW -> CS_INIT
2006-09-29 03:03:49 [DEBUG] switch_core.c:2673 switch_core_session_run() (sofia/test/5413899211@66.39.160.3) State INIT
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:718 sofia_on_init() SOFIA INIT
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:730 sofia_on_init() sofia/test/5413899211@66.39.160.3 State Change CS_INIT -> CS_RING
2006-09-29 03:03:49 [DEBUG] switch_core.c:2709 switch_core_session_run() (sofia/test/5413899211@66.39.160.3) State RING
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:746 sofia_on_ring() SOFIA RING
2006-09-29 03:03:49 [DEBUG] switch_core.c:2396 switch_core_standard_on_ring() Standard RING sofia/test/5413899211@66.39.160.3
2006-09-29 03:03:49 [INFO] switch_core.c:2408 switch_core_standard_on_ring() No Dialplan, changing state to HOLD
2006-09-29 03:03:49 [DEBUG] switch_core.c:2409 switch_core_standard_on_ring() sofia/test/5413899211@66.39.160.3 State Change CS_RING -> CS_HOLD
2006-09-29 03:03:49 [DEBUG] switch_core.c:2854 switch_core_session_run() (sofia/test/5413899211@66.39.160.3) State HOLD
2006-09-29 03:03:49 [DEBUG] switch_core.c:2497 switch_core_standard_on_hold() Standard HOLD
su_localinfo: SIOCGIFINDEX failed: Device not configured
su_localinfo: SIOCGIFINDEX failed: Device not configured
su_localinfo: SIOCGIFINDEX failed: Device not configured
su_localinfo: SIOCGIFINDEX failed: Device not configured
nta_leg_create(0x8232200)
su_localinfo: SIOCGIFINDEX failed: Device not configured
su_localinfo: SIOCGIFINDEX failed: Device not configured
nta_leg_destroy(0x8232200)
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:2308 event_callback() channel [sofia/test/5413899211@66.39.160.3] event [nua_r_invite] status [900] [Internal NUA Error]
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:2231 sip_r_invite() event AUTHORIZE scheme [Digest], realm [5413239321], username [5413239321], pass [zon3ing]
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:2308 event_callback() channel [sofia/test/5413899211@66.39.160.3] event [nua_i_state] status [900] [Internal NUA Error]
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:1595 sip_i_state() Channel sofia/test/5413899211@66.39.160.3 entering state [terminated]
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:569 terminate_session() Term called from line: 1734
2006-09-29 03:03:49 [NOTICE] mod_sofia.c:579 terminate_session() Hangup sofia/test/5413899211@66.39.160.3 [CS_HOLD] [NORMAL_UNSPECIFIED]
2006-09-29 03:03:49 [INFO] switch_channel.c:907 switch_channel_perform_hangup() Kill sofia/test/5413899211@66.39.160.3 [1]
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:2308 event_callback() channel [sofia/test/5413899211@66.39.160.3] event [nua_i_terminated] status [900] [Internal NUA Error]
API CALL [originate(sofia/test/5413899211@66.39.160.3)] output:
Cannot Create Outgoing Channel! [sofia/test/5413899211@66.39.160.3]
freeswitch@odin.zonedzero.net> 2006-09-29 03:03:49 [DEBUG] switch_ivr.c:2023 switch_ivr_originate() Originate Resulted in Error Cause: 31 [NORMAL_UNSPECIFIED]
2006-09-29 03:03:49 [DEBUG] switch_core.c:2635 switch_core_session_run() (sofia/test/5413899211@66.39.160.3) State HANGUP
2006-09-29 03:03:49 [DEBUG] mod_sofia.c:839 sofia_on_hangup() Channel sofia/test/5413899211@66.39.160.3 hanging up, cause: NORMAL_UNSPECIFIED
2006-09-29 03:03:49 [DEBUG] mod_ivrtest.c:225 my_on_hangup() I globally hooked to [sofia/test/5413899211@66.39.160.3] on the hangup event
2006-09-29 03:03:49 [DEBUG] switch_core.c:2386 switch_core_standard_on_hangup() Standard HANGUP sofia/test/5413899211@66.39.160.3, cause: NORMAL_UNSPECIFIED
2006-09-29 03:03:49 [DEBUG] switch_core.c:3043 switch_core_session_thread() Session 2 (sofia/test/5413899211@66.39.160.3) Locked, Waiting on external entities
2006-09-29 03:03:49 [INFO] switch_core.c:3050 switch_core_session_thread() Session 2 (sofia/test/5413899211@66.39.160.3) Ended
2006-09-29 03:03:49 [NOTICE] switch_core.c:2923 switch_core_session_destroy() Close Channel sofia/test/5413899211@66.39.160.3