[Feb 18 17:15:23] NOTICE[10406] cdr.c: CDR simple logging enabled. [Feb 18 17:15:23] NOTICE[10406] loader.c: 152 modules will be loaded. [Feb 18 17:15:23] NOTICE[10406] res_jabber.c: res_jabber.so loaded. [Feb 18 17:15:23] WARNING[10406] res_smdi.c: No SMDI interfaces are available to listen on, not starting SDMI listener. [Feb 18 17:15:23] WARNING[10406] translate.c: plc_samples 160 format 6 [Feb 18 17:15:23] ERROR[10406] pbx.c: Function ENV not registered [Feb 18 17:15:24] WARNING[10406] pbx_config.c: ==!!== Unknown directive: ${DIALOPT} at line 296 -- IGNORING!!! [Feb 18 17:15:24] WARNING[10406] pbx_config.c: ==!!== Unknown directive: ${DIALOPT} at line 381 -- IGNORING!!! [Feb 18 17:15:24] WARNING[10406] translate.c: plc_samples 160 format 6 [Feb 18 17:15:24] NOTICE[10406] codec_g729.c: G.729 transcoding module Copyright (C) 1999-2006 Digium, Inc. [Feb 18 17:15:24] NOTICE[10406] codec_g729.c: This module is supplied under a commercial license granted by Digium, Inc. [Feb 18 17:15:24] NOTICE[10406] codec_g729.c: Please see the full license text supplied by the accompanying [Feb 18 17:15:24] NOTICE[10406] codec_g729.c: "register" utility, or ask for a copy from Digium. [Feb 18 17:15:24] NOTICE[10406] pbx_dundi.c: No ethernet interface found for seeding global EID You will have to set it manually. [Feb 18 17:15:24] NOTICE[10406] app_queue.c: Queue members successfully reloaded from database. [Feb 18 17:15:24] WARNING[10406] translate.c: plc_samples 160 format 6 [Feb 18 17:15:24] WARNING[10406] translate.c: plc_samples 160 format 6 [Feb 18 17:15:24] WARNING[10406] translate.c: plc_samples 160 format 6 [Feb 18 17:15:24] WARNING[10406] translate.c: plc_samples 160 format 6 [Feb 18 17:15:24] WARNING[10406] translate.c: plc_samples 160 format 6 [Feb 18 17:15:24] WARNING[10406] translate.c: plc_samples 180 format 6 [Feb 18 17:15:46] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:15:50] NOTICE[10406] pbx_ael.c: Starting AEL load process. [Feb 18 17:15:50] NOTICE[10406] pbx_ael.c: AEL load process: calculated config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:15:50] WARNING[10406] ael.y: ==== File: /etc/asterisk/extensions.ael, Line 112, Cols: 34-34: Warning! The empty context ael-dundi-e164-canonical will be IGNORED! [Feb 18 17:15:50] WARNING[10406] ael.y: ==== File: /etc/asterisk/extensions.ael, Line 120, Cols: 34-34: Warning! The empty context ael-dundi-e164-customers will be IGNORED! [Feb 18 17:15:50] WARNING[10406] ael.y: ==== File: /etc/asterisk/extensions.ael, Line 128, Cols: 33-33: Warning! The empty context ael-dundi-e164-via-pstn will be IGNORED! [Feb 18 17:15:50] NOTICE[10406] pbx_ael.c: AEL load process: parsed config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:15:50] WARNING[10406] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 141-145: The included context 'ael-dundi-e164-canonical' cannot be found. [Feb 18 17:15:50] WARNING[10406] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 141-145: The included context 'ael-dundi-e164-customers' cannot be found. [Feb 18 17:15:50] WARNING[10406] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 141-145: The included context 'ael-dundi-e164-via-pstn' cannot be found. [Feb 18 17:15:50] WARNING[10406] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 174-179: The macro ael-dundi-e164 does not end with a return; I will insert one. [Feb 18 17:15:50] WARNING[10406] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 308-321: The macro ael-std-exten-ael does not end with a return; I will insert one. [Feb 18 17:15:50] NOTICE[10406] pbx_ael.c: AEL load process: checked config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:15:50] NOTICE[10406] pbx_ael.c: AEL load process: compiled config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:15:50] NOTICE[10406] pbx_ael.c: AEL load process: merged config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:15:50] WARNING[10406] pbx.c: Context 'ael-dundi-e164-local' tries includes nonexistent context 'ael-dundi-e164-canonical' [Feb 18 17:15:50] WARNING[10406] pbx.c: Context 'ael-dundi-e164-local' tries includes nonexistent context 'ael-dundi-e164-customers' [Feb 18 17:15:50] WARNING[10406] pbx.c: Context 'ael-dundi-e164-local' tries includes nonexistent context 'ael-dundi-e164-via-pstn' [Feb 18 17:15:50] NOTICE[10406] pbx_ael.c: AEL load process: verified config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:15:51] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:15:51] WARNING[10406] chan_sip.c: Forbidden - wrong password on authentication for REGISTER for '6839026' to 'freevoip.gedameurope.com' [Feb 18 17:15:52] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:15:53] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:15:55] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:15:59] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:03] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:07] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:11] NOTICE[10406] chan_sip.c: -- Registration for 'tootai@wengo' timed out, trying again (Attempt #1) [Feb 18 17:16:11] WARNING[10406] chan_sip.c: sip_xmit of 0x8278d98 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:12] WARNING[10406] chan_sip.c: sip_xmit of 0x8278d98 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:13] WARNING[10406] chan_sip.c: sip_xmit of 0x8278d98 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:15] WARNING[10406] chan_sip.c: sip_xmit of 0x8278d98 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:19] WARNING[10406] chan_sip.c: sip_xmit of 0x8278d98 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:23] WARNING[10406] chan_sip.c: sip_xmit of 0x8278d98 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:27] WARNING[10406] chan_sip.c: sip_xmit of 0x8278d98 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:27] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:16:28] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:16:29] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:16:29] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:16:31] NOTICE[10406] chan_sip.c: -- Registration for 'tootai@wengo' timed out, trying again (Attempt #2) [Feb 18 17:16:31] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:32] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:33] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:35] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:38] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:16:39] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:43] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:46] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:16:47] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:51] NOTICE[10406] chan_sip.c: -- Registration for 'tootai@wengo' timed out, trying again (Attempt #3) [Feb 18 17:16:51] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:52] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:53] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:55] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:16:59] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:03] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:07] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:11] NOTICE[10406] chan_sip.c: -- Registration for 'tootai@wengo' timed out, trying again (Attempt #4) [Feb 18 17:17:11] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:12] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:13] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:15] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:18] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:17:18] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:17:19] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:23] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:24] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:17:24] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:17:24] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:17:27] WARNING[10406] chan_sip.c: sip_xmit of 0x826f160 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:31] NOTICE[10406] chan_sip.c: -- Registration for 'tootai@wengo' timed out, trying again (Attempt #5) [Feb 18 17:17:31] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:32] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:33] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:35] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:38] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:17:39] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:43] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:46] ERROR[10406] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:17:47] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:51] NOTICE[10406] chan_sip.c: -- Registration for 'tootai@wengo' timed out, trying again (Attempt #6) [Feb 18 17:17:51] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:17:52] WARNING[10406] chan_sip.c: sip_xmit of 0x826ef58 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:18:58] NOTICE[10565] cdr.c: CDR simple logging enabled. [Feb 18 17:18:58] NOTICE[10565] loader.c: 152 modules will be loaded. [Feb 18 17:18:59] NOTICE[10565] res_jabber.c: res_jabber.so loaded. [Feb 18 17:18:59] WARNING[10565] res_smdi.c: No SMDI interfaces are available to listen on, not starting SDMI listener. [Feb 18 17:18:59] WARNING[10565] translate.c: plc_samples 160 format 6 [Feb 18 17:18:59] ERROR[10565] pbx.c: Function ENV not registered [Feb 18 17:18:59] WARNING[10565] pbx_config.c: ==!!== Unknown directive: ${DIALOPT} at line 296 -- IGNORING!!! [Feb 18 17:18:59] WARNING[10565] pbx_config.c: ==!!== Unknown directive: ${DIALOPT} at line 381 -- IGNORING!!! [Feb 18 17:18:59] WARNING[10565] translate.c: plc_samples 160 format 6 [Feb 18 17:18:59] NOTICE[10565] codec_g729.c: G.729 transcoding module Copyright (C) 1999-2006 Digium, Inc. [Feb 18 17:18:59] NOTICE[10565] codec_g729.c: This module is supplied under a commercial license granted by Digium, Inc. [Feb 18 17:18:59] NOTICE[10565] codec_g729.c: Please see the full license text supplied by the accompanying [Feb 18 17:18:59] NOTICE[10565] codec_g729.c: "register" utility, or ask for a copy from Digium. [Feb 18 17:18:59] NOTICE[10565] pbx_dundi.c: No ethernet interface found for seeding global EID You will have to set it manually. [Feb 18 17:18:59] NOTICE[10565] app_queue.c: Queue members successfully reloaded from database. [Feb 18 17:18:59] WARNING[10565] translate.c: plc_samples 160 format 6 [Feb 18 17:18:59] WARNING[10565] translate.c: plc_samples 160 format 6 [Feb 18 17:18:59] WARNING[10565] translate.c: plc_samples 160 format 6 [Feb 18 17:18:59] WARNING[10565] translate.c: plc_samples 160 format 6 [Feb 18 17:18:59] WARNING[10565] translate.c: plc_samples 160 format 6 [Feb 18 17:18:59] WARNING[10565] translate.c: plc_samples 180 format 6 [Feb 18 17:19:03] NOTICE[10565] pbx_ael.c: Starting AEL load process. [Feb 18 17:19:03] NOTICE[10565] pbx_ael.c: AEL load process: calculated config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:19:03] WARNING[10565] ael.y: ==== File: /etc/asterisk/extensions.ael, Line 112, Cols: 34-34: Warning! The empty context ael-dundi-e164-canonical will be IGNORED! [Feb 18 17:19:03] WARNING[10565] ael.y: ==== File: /etc/asterisk/extensions.ael, Line 120, Cols: 34-34: Warning! The empty context ael-dundi-e164-customers will be IGNORED! [Feb 18 17:19:03] WARNING[10565] ael.y: ==== File: /etc/asterisk/extensions.ael, Line 128, Cols: 33-33: Warning! The empty context ael-dundi-e164-via-pstn will be IGNORED! [Feb 18 17:19:03] NOTICE[10565] pbx_ael.c: AEL load process: parsed config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:19:03] WARNING[10565] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 141-145: The included context 'ael-dundi-e164-canonical' cannot be found. [Feb 18 17:19:03] WARNING[10565] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 141-145: The included context 'ael-dundi-e164-customers' cannot be found. [Feb 18 17:19:03] WARNING[10565] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 141-145: The included context 'ael-dundi-e164-via-pstn' cannot be found. [Feb 18 17:19:03] WARNING[10565] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 174-179: The macro ael-dundi-e164 does not end with a return; I will insert one. [Feb 18 17:19:03] WARNING[10565] pbx_ael.c: Warning: file /etc/asterisk/extensions.ael, line 308-321: The macro ael-std-exten-ael does not end with a return; I will insert one. [Feb 18 17:19:03] NOTICE[10565] pbx_ael.c: AEL load process: checked config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:19:03] NOTICE[10565] pbx_ael.c: AEL load process: compiled config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:19:03] NOTICE[10565] pbx_ael.c: AEL load process: merged config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:19:03] WARNING[10565] pbx.c: Context 'ael-dundi-e164-local' tries includes nonexistent context 'ael-dundi-e164-canonical' [Feb 18 17:19:03] WARNING[10565] pbx.c: Context 'ael-dundi-e164-local' tries includes nonexistent context 'ael-dundi-e164-customers' [Feb 18 17:19:03] WARNING[10565] pbx.c: Context 'ael-dundi-e164-local' tries includes nonexistent context 'ael-dundi-e164-via-pstn' [Feb 18 17:19:03] NOTICE[10565] pbx_ael.c: AEL load process: verified config file name '/etc/asterisk/extensions.ael'. [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: ---------- SIP HISTORY for '10591a2867ada54631fe0a7d74584d68@192.168.10.250' [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: * SIP Call [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: 001. SchedDestroy 32000 ms [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: 002. TxReqRel NOTIFY / 102 NOTIFY - -UNKNOWN- [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: 003. Rx SIP/2.0 / 102 NOTIFY / 200 OK [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: ---------- END SIP HISTORY for '10591a2867ada54631fe0a7d74584d68@192.168.10.250' [Feb 18 17:19:04] NOTICE[10565] chan_sip.c: -- Re-registration for tootai@wengo [Feb 18 17:19:04] WARNING[10565] chan_sip.c: sip_xmit of 0x826dfd0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:04] NOTICE[10565] chan_sip.c: -- Re-registration for tootaiVOIP@tootaiAUDIO [Feb 18 17:19:04] NOTICE[10565] chan_sip.c: -- Re-registration for 5553238@sipgate.de [Feb 18 17:19:04] NOTICE[10565] chan_sip.c: -- Re-registration for 77181@TNET-ipac [Feb 18 17:19:04] NOTICE[10565] chan_sip.c: Outbound Registration: Expiry for tootaiAUDIO is 120 sec (Scheduling reregistration in 105 s) [Feb 18 17:19:04] NOTICE[10565] chan_sip.c: -- Re-registration for 6839026@freevoip.gedameurope.com [Feb 18 17:19:04] NOTICE[10565] chan_sip.c: Outbound Registration: Expiry for TNET-ipac is 120 sec (Scheduling reregistration in 105 s) [Feb 18 17:19:04] NOTICE[10565] chan_sip.c: Outbound Registration: Expiry for sipgate.de is 120 sec (Scheduling reregistration in 105 s) [Feb 18 17:19:04] WARNING[10565] chan_sip.c: Forbidden - wrong password on authentication for REGISTER for '6839026' to 'freevoip.gedameurope.com' [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: ---------- SIP HISTORY for '599fa8e62393c92b681493377a96c162@192.168.10.250' [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: * SIP Call [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: 001. RegistryInit Account: 6839026@freevoip.gedameurope.com [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: 002. TxReqRel REGISTER / 102 REGISTER - -UNKNOWN- [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: 003. Rx SIP/2.0 / 102 REGISTER / 403 Forbidden [Feb 18 17:19:04] DEBUG[10565] chan_sip.c: ---------- END SIP HISTORY for '599fa8e62393c92b681493377a96c162@192.168.10.250' [Feb 18 17:19:05] WARNING[10565] chan_sip.c: sip_xmit of 0x826dfd0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:06] WARNING[10565] chan_sip.c: sip_xmit of 0x826dfd0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:08] WARNING[10565] chan_sip.c: sip_xmit of 0x826dfd0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:12] WARNING[10565] chan_sip.c: sip_xmit of 0x826dfd0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:14] ERROR[10565] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:19:14] ERROR[10565] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:19:16] WARNING[10565] chan_sip.c: sip_xmit of 0x826dfd0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:17] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:17] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:18] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:19] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:20] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #42 (6) REGISTER - 2 [Feb 18 17:19:20] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #42)) [Feb 18 17:19:20] WARNING[10565] chan_sip.c: sip_xmit of 0x826dfd0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:20] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:21] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:22] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:23] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: SIP Registry wengo: refcount now 3 [Feb 18 17:19:24] NOTICE[10565] chan_sip.c: -- Registration for 'tootai@wengo' timed out, trying again (Attempt #1) [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: SIP Registry wengo: refcount now 2 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #42 [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_del() [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Stopping retransmission on '158ff5a453948d22086583c1636bf583@voip.wengo.fr' of Request 102: Match Found [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Allocating new SIP dialog for 158ff5a453948d22086583c1636bf583@voip.wengo.fr - REGISTER (No RTP) [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: OBPROXY: Applying peer OBproxy to this call [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: SIP Registry wengo: refcount now 3 [Feb 18 17:19:24] DEBUG[10565] acl.c: ##### Testing 213.91.9.206 with 192.168.0.0 [Feb 18 17:19:24] DEBUG[10565] acl.c: ##### Testing 213.91.9.206 with 83.22.178.92 [Feb 18 17:19:24] DEBUG[10565] acl.c: ##### Testing 83.22.178.92 with 192.168.0.0 [Feb 18 17:19:24] DEBUG[10565] acl.c: ##### Testing 83.22.178.92 with 83.22.178.92 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Target address 213.91.9.206 is not local, substituting externip [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Scheduled a registration timeout for wengo id #61 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Initializing initreq for method REGISTER - callid 158ff5a453948d22086583c1636bf583@voip.wengo.fr [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 0 [ 34]: REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 83.22.178.92:5060;branch=z9hG4bK2cc4133f;rport [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 3 [ 47]: From: ;tag=as0a7cf9f3 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 4 [ 30]: To: [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 5 [ 55]: Call-ID: 158ff5a453948d22086583c1636bf583@voip.wengo.fr [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 6 [ 18]: CSeq: 103 REGISTER [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 7 [ 22]: User-Agent: TOOTAi PBX [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 8 [ 12]: Expires: 120 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 9 [ 34]: Contact: [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 10 [ 19]: Event: registration [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 0 [ 34]: REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 83.22.178.92:5060;branch=z9hG4bK2cc4133f;rport [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 3 [ 47]: From: ;tag=as0a7cf9f3 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 4 [ 30]: To: [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 5 [ 55]: Call-ID: 158ff5a453948d22086583c1636bf583@voip.wengo.fr [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 6 [ 18]: CSeq: 103 REGISTER [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 7 [ 22]: User-Agent: TOOTAi PBX [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 8 [ 12]: Expires: 120 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 9 [ 34]: Contact: [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 10 [ 19]: Event: registration [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: Header 12 [ 0]: [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #62 [Feb 18 17:19:24] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: SIP Registry wengo: refcount now 2 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: ---------- SIP HISTORY for '158ff5a453948d22086583c1636bf583@voip.wengo.fr' [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: * SIP Call [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 001. RegistryInit Account: tootai@wengo [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 002. OBproxy Using peer obproxy voip.wengo.fr [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 003. TxReqRel REGISTER / 102 REGISTER - -UNKNOWN- [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 004. ReTx 1000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 005. ReTx 2000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 006. ReTx 4000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 007. ReTx 4000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 008. ReTx 4000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: 009. ReTx 4000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:24] DEBUG[10565] chan_sip.c: ---------- END SIP HISTORY for '158ff5a453948d22086583c1636bf583@voip.wengo.fr' [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:24] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:25] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #62 (1) REGISTER - 2 [Feb 18 17:19:25] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #62)) [Feb 18 17:19:25] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:25] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:26] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #62 (2) REGISTER - 2 [Feb 18 17:19:26] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #62)) [Feb 18 17:19:26] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:26] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:27] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:28] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #62 (3) REGISTER - 2 [Feb 18 17:19:28] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #62)) [Feb 18 17:19:28] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:28] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:29] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:30] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:31] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:32] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #62 (4) REGISTER - 2 [Feb 18 17:19:32] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #62)) [Feb 18 17:19:32] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:32] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:33] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:34] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:35] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #62 (5) REGISTER - 2 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #62)) [Feb 18 17:19:36] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: Auto destroying SIP dialog '4d6a07aa7cf27e334cc9285871a31f88@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: Destroying SIP dialog 4d6a07aa7cf27e334cc9285871a31f88@192.168.10.250 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: ---------- SIP HISTORY for '4d6a07aa7cf27e334cc9285871a31f88@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: * SIP Call [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 001. RegistryInit Account: tootaiVOIP@tootaiAUDIO [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 002. TxReqRel REGISTER / 102 REGISTER - -UNKNOWN- [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 003. Rx SIP/2.0 / 102 REGISTER / 100 Trying [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 004. Rx SIP/2.0 / 102 REGISTER / 401 Unauthorized [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 005. AuthResp Auth response sent for tootaiVOIP in realm sip.tootai.net - nc [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 006. RegistryAuth Try: 1 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 007. TxReqRel REGISTER / 103 REGISTER - -UNKNOWN- [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 008. Rx SIP/2.0 / 103 REGISTER / 100 Trying [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 009. Rx SIP/2.0 / 103 REGISTER / 200 OK [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 010. SchedDestroy 32000 ms [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 011. AutoDestroy 4d6a07aa7cf27e334cc9285871a31f88@192.168.10.250 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: ---------- END SIP HISTORY for '4d6a07aa7cf27e334cc9285871a31f88@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: Auto destroying SIP dialog '22a2b0925d6d908e4f2e6c0f06974d91@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: Destroying SIP dialog 22a2b0925d6d908e4f2e6c0f06974d91@192.168.10.250 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: ---------- SIP HISTORY for '22a2b0925d6d908e4f2e6c0f06974d91@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: * SIP Call [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 001. RegistryInit Account: 77181@TNET-ipac [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 002. TxReqRel REGISTER / 102 REGISTER - -UNKNOWN- [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 003. Rx SIP/2.0 / 102 REGISTER / 100 Trying [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 004. Rx SIP/2.0 / 102 REGISTER / 401 Unauthorized [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 005. AuthResp Auth response sent for 77181 in realm ifon2 - nc 1 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 006. RegistryAuth Try: 1 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 007. TxReqRel REGISTER / 103 REGISTER - -UNKNOWN- [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 008. Rx SIP/2.0 / 103 REGISTER / 100 Trying [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 009. Rx SIP/2.0 / 103 REGISTER / 200 OK [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 010. SchedDestroy 32000 ms [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 011. AutoDestroy 22a2b0925d6d908e4f2e6c0f06974d91@192.168.10.250 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: ---------- END SIP HISTORY for '22a2b0925d6d908e4f2e6c0f06974d91@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: Auto destroying SIP dialog '3a2332ee127dcab91e7986c81e5e41aa@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: Destroying SIP dialog 3a2332ee127dcab91e7986c81e5e41aa@192.168.10.250 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: ---------- SIP HISTORY for '3a2332ee127dcab91e7986c81e5e41aa@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: * SIP Call [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 001. RegistryInit Account: 5553238@sipgate.de [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 002. TxReqRel REGISTER / 102 REGISTER - -UNKNOWN- [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 003. Rx SIP/2.0 / 102 REGISTER / 401 Unauthorized [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 004. AuthResp Auth response sent for 5553238 in realm sipgate.de - nc 1 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 005. RegistryAuth Try: 1 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 006. TxReqRel REGISTER / 103 REGISTER - -UNKNOWN- [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 007. Rx SIP/2.0 / 103 REGISTER / 200 OK [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 008. SchedDestroy 32000 ms [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: 009. AutoDestroy 3a2332ee127dcab91e7986c81e5e41aa@192.168.10.250 [Feb 18 17:19:36] DEBUG[10565] chan_sip.c: ---------- END SIP HISTORY for '3a2332ee127dcab91e7986c81e5e41aa@192.168.10.250' [Feb 18 17:19:36] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: Auto destroying SIP dialog '7807d4683916c3dc0aa451be5cb723d0@217.119.64.58' [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: Destroying SIP dialog 7807d4683916c3dc0aa451be5cb723d0@217.119.64.58 [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: ---------- SIP HISTORY for '7807d4683916c3dc0aa451be5cb723d0@217.119.64.58' [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: * SIP Call [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: 001. Rx OPTIONS / 102 OPTIONS / sip:0224346431@83.22.178.92 [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: 002. TxResp SIP/2.0 / 102 OPTIONS - 200 OK [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: 003. SchedDestroy 32000 ms [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: 004. AutoDestroy 7807d4683916c3dc0aa451be5cb723d0@217.119.64.58 [Feb 18 17:19:37] DEBUG[10565] chan_sip.c: ---------- END SIP HISTORY for '7807d4683916c3dc0aa451be5cb723d0@217.119.64.58' [Feb 18 17:19:37] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:38] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:39] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:40] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #62 (6) REGISTER - 2 [Feb 18 17:19:40] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #62)) [Feb 18 17:19:40] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:40] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:41] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:42] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:43] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: SIP Registry wengo: refcount now 3 [Feb 18 17:19:44] NOTICE[10565] chan_sip.c: -- Registration for 'tootai@wengo' timed out, trying again (Attempt #2) [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: SIP Registry wengo: refcount now 2 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #62 [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_del() [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Stopping retransmission on '158ff5a453948d22086583c1636bf583@voip.wengo.fr' of Request 103: Match Found [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Allocating new SIP dialog for 158ff5a453948d22086583c1636bf583@voip.wengo.fr - REGISTER (No RTP) [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: OBPROXY: Applying peer OBproxy to this call [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: SIP Registry wengo: refcount now 3 [Feb 18 17:19:44] DEBUG[10565] acl.c: ##### Testing 213.91.9.206 with 192.168.0.0 [Feb 18 17:19:44] DEBUG[10565] acl.c: ##### Testing 213.91.9.206 with 83.22.178.92 [Feb 18 17:19:44] DEBUG[10565] acl.c: ##### Testing 83.22.178.92 with 192.168.0.0 [Feb 18 17:19:44] DEBUG[10565] acl.c: ##### Testing 83.22.178.92 with 83.22.178.92 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Target address 213.91.9.206 is not local, substituting externip [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Scheduled a registration timeout for wengo id #63 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Initializing initreq for method REGISTER - callid 158ff5a453948d22086583c1636bf583@voip.wengo.fr [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 0 [ 34]: REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 83.22.178.92:5060;branch=z9hG4bK600ff18c;rport [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 3 [ 47]: From: ;tag=as331835e2 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 4 [ 30]: To: [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 5 [ 55]: Call-ID: 158ff5a453948d22086583c1636bf583@voip.wengo.fr [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 6 [ 18]: CSeq: 104 REGISTER [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 7 [ 22]: User-Agent: TOOTAi PBX [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 8 [ 12]: Expires: 120 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 9 [ 34]: Contact: [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 10 [ 19]: Event: registration [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 0 [ 34]: REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 83.22.178.92:5060;branch=z9hG4bK600ff18c;rport [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 3 [ 47]: From: ;tag=as331835e2 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 4 [ 30]: To: [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 5 [ 55]: Call-ID: 158ff5a453948d22086583c1636bf583@voip.wengo.fr [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 6 [ 18]: CSeq: 104 REGISTER [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 7 [ 22]: User-Agent: TOOTAi PBX [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 8 [ 12]: Expires: 120 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 9 [ 34]: Contact: [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 10 [ 19]: Event: registration [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: Header 12 [ 0]: [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #64 [Feb 18 17:19:44] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: SIP Registry wengo: refcount now 2 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: ---------- SIP HISTORY for '158ff5a453948d22086583c1636bf583@voip.wengo.fr' [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: * SIP Call [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 001. RegistryInit Account: tootai@wengo [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 002. OBproxy Using peer obproxy voip.wengo.fr [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 003. TxReqRel REGISTER / 103 REGISTER - -UNKNOWN- [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 004. ReTx 1000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 005. ReTx 2000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 006. ReTx 4000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 007. ReTx 4000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 008. ReTx 4000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: 009. ReTx 4000 REGISTER sip:voip.wengo.fr SIP/2.0 [Feb 18 17:19:44] DEBUG[10565] chan_sip.c: ---------- END SIP HISTORY for '158ff5a453948d22086583c1636bf583@voip.wengo.fr' [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:44] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:45] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #64 (1) REGISTER - 2 [Feb 18 17:19:45] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #64)) [Feb 18 17:19:45] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:45] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:46] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #64 (2) REGISTER - 2 [Feb 18 17:19:46] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #64)) [Feb 18 17:19:46] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:46] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:47] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:48] DEBUG[10565] chan_sip.c: SIP TIMER: Rescheduling retransmission #64 (3) REGISTER - 2 [Feb 18 17:19:48] DEBUG[10565] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #64)) [Feb 18 17:19:48] WARNING[10565] chan_sip.c: sip_xmit of 0x82650f0 (len 393) to 213.91.9.206:0 returned -1: Invalid argument [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:48] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:49] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] chan_iax2.c: Allocate call number [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] chan_iax2.c: Registration created on call 8 [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] res_musiconhold.c: killing 10569! [Feb 18 17:19:50] ERROR[10565] /usr/src/asterisk-trunk/include/asterisk/lock.h: chan_iax2.c line 6579 (socket_process): Warning: '&iaxsl[f->callno]' was locked here. [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] chan_iax2.c: Allocate call number [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] chan_iax2.c: Registration created on call 9 [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_del() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_del() [Feb 18 17:19:50] DEBUG[10565] sched.c: ast_sched_del() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:51] DEBUG[10565] chan_iax2.c: Allocate call number [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:51] DEBUG[10565] chan_iax2.c: Registration created on call 10 [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_add() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_wait() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_runq() [Feb 18 17:19:51] DEBUG[10565] sched.c: ast_sched_wait()