lock*CLI> core set verbose 15 lock*CLI> Verbosity was 3 and is now 15 lock*CLI> core set debug 15 lock*CLI> Core debug was 0 and is now 15 lock*CLI> iax2 set debug on lock*CLI> IAX2 Debugging Enabled lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00001 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00001 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 CALLTOKEN : 51 bytes lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835249, regseconds=0, diff=1291835249, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835249, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835250, regseconds=0, diff=1291835250, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835250, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835250, regseconds=0, diff=1291835250, IAX_DEFAULT_REG_EXPIRE=60 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835250, regseconds=0, diff=1291835250, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835250, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 2 - realtime_update_peer: nowtime=1291835250, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Timestamp: 00017ms SCall: 02780 DCall: 00001 [94.2.84.148:2688] AUTHMETHODS : 2 CHALLENGE : \x31\x37\x37\x34\x33\x35\x38\x35\x38 USERNAME : phonefromhere-201 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00001 DCall: 02780 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 MD5 RESULT : 7fa34bbed41494b087db8460703c332b lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835250, regseconds=0, diff=1291835250, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835250, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835250, regseconds=0, diff=1291835250, IAX_DEFAULT_REG_EXPIRE=60 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835250, regseconds=0, diff=1291835250, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835250, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 5.1 - update_registry, nowtime=1291835250 > birgit 2 - realtime_update_peer: nowtime=1291835250, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 2 - realtime_update_peer: nowtime=1291835250, peer=phonefromhere-201, ipaddr=94.2.84.148, port=2688, regtime=1291835250 > birgit 6 - update_registry, schedule expire, p->expiry=60, total expire=70000 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK Timestamp: 00074ms SCall: 02780 DCall: 00001 [94.2.84.148:2688] USERNAME : phonefromhere-201 DATE TIME : 2010-12-08 19:07:30 REFRESH : 60 APPARENT ADDRES : IPV4 94.2.84.148:2688 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00001 DCall: 02780 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00002 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00002 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 CALLTOKEN : 51 bytes lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835250 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835250 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835250 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835250 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835250 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Timestamp: 00019ms SCall: 03825 DCall: 00002 [94.2.84.148:2688] AUTHMETHODS : 2 CHALLENGE : \x31\x35\x36\x31\x30\x37\x32\x37\x34 USERNAME : phonefromhere-201 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00002 DCall: 03825 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 MD5 RESULT : 7de780801f4749daa8607123fd43545e lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835250 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835250 > birgit 5.1 - update_registry, nowtime=1291835301 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835250 > birgit 2 - realtime_update_peer: nowtime=1291835301, peer=phonefromhere-201, ipaddr=94.2.84.148, port=2688, regtime=1291835301 > birgit 6 - update_registry, schedule expire, p->expiry=60, total expire=70000 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK Timestamp: 00069ms SCall: 03825 DCall: 00002 [94.2.84.148:2688] USERNAME : phonefromhere-201 DATE TIME : 2010-12-08 19:08:20 REFRESH : 60 APPARENT ADDRES : IPV4 94.2.84.148:2688 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00002 DCall: 03825 [94.2.84.148:2688] lock*CLI> > birgit 3 - __expire_registry peer=phonefromhere-201 > birgit 2 - realtime_update_peer: nowtime=1291835320, peer=phonefromhere-201, ipaddr=94.2.84.148, port=2688, regtime=0 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00003 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00003 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 CALLTOKEN : 51 bytes lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835351, regseconds=0, diff=1291835351, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835351, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835351, regseconds=0, diff=1291835351, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835351, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835351, regseconds=0, diff=1291835351, IAX_DEFAULT_REG_EXPIRE=60 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835351, regseconds=0, diff=1291835351, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835351, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Timestamp: 00018ms SCall: 00458 DCall: 00003 [94.2.84.148:2688] AUTHMETHODS : 2 CHALLENGE : \x32\x30\x33\x33\x31\x36\x36\x39\x32 USERNAME : phonefromhere-201 lock*CLI> > birgit 2 - realtime_update_peer: nowtime=1291835351, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00003 DCall: 00458 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 MD5 RESULT : 6dd2f342319a3cff7f1fe4883a792ef4 lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835352, regseconds=0, diff=1291835352, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835352, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835352, regseconds=0, diff=1291835352, IAX_DEFAULT_REG_EXPIRE=60 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835352, regseconds=0, diff=1291835352, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835352, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 5.1 - update_registry, nowtime=1291835352 > birgit 2 - realtime_update_peer: nowtime=1291835352, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 2 - realtime_update_peer: nowtime=1291835352, peer=phonefromhere-201, ipaddr=94.2.84.148, port=2688, regtime=1291835352 > birgit 6 - update_registry, schedule expire, p->expiry=60, total expire=70000 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK Timestamp: 00084ms SCall: 00458 DCall: 00003 [94.2.84.148:2688] USERNAME : phonefromhere-201 DATE TIME : 2010-12-08 19:09:12 REFRESH : 60 APPARENT ADDRES : IPV4 94.2.84.148:2688 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00003 DCall: 00458 [94.2.84.148:2688] lock*CLI> == Using SIP RTP CoS mark 5 lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:1] Set("SIP/ipcentrex-artemis-in-00000000", "GROUP()=applets") in new stack lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:2] GotoIf("SIP/ipcentrex-artemis-in-00000000", "1?5") in new stack lock*CLI> -- Goto (from-ipcentrex-to-webphone-1,201,5) lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:5] Wait("SIP/ipcentrex-artemis-in-00000000", "1") in new stack lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:6] Answer("SIP/ipcentrex-artemis-in-00000000", "") in new stack lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:7] NoOp("SIP/ipcentrex-artemis-in-00000000", "201") in new stack -- Executing [201@from-ipcentrex-to-webphone-1:8] NoOp("SIP/ipcentrex-artemis-in-00000000", ""01617985039" <01617985039>") in new stack -- Executing [201@from-ipcentrex-to-webphone-1:9] Dial("SIP/ipcentrex-artemis-in-00000000", "IAX2/phonefromhere-201/201,20,m") in new stack lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835352 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835352 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835352 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00019ms SCall: 03258 DCall: 00000 [94.2.84.148:2688] VERSION : 2 CALLED NUMBER : 201 CODEC_PREFS : (gsm) CALLING NUMBER : 01617985039 CALLING PRESNTN : 0 CALLING TYPEOFN : 0 -- Called phonefromhere-201/201 CALLING TRANSIT : 0 CALLING NAME : 01617985039 LANGUAGE : en USERNAME : phonefromhere-201 FORMAT : 2 FORMAT2 : gsm CAPABILITY : 2 CAPABILITY2 : gsm ADSICPE : 2 DATE TIME : 2010-12-08 19:09:26 lock*CLI> -- Started music on hold, class 'default', on SIP/ipcentrex-artemis-in-00000000 lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=1291835352 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] CALLTOKEN : 59 bytes lock*CLI> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00071ms SCall: 03258 DCall: 00000 [94.2.84.148:2688] VERSION : 2 CALLED NUMBER : 201 CODEC_PREFS : (gsm) CALLING NUMBER : 01617985039 CALLING PRESNTN : 0 CALLING TYPEOFN : 0 CALLING TRANSIT : 0 CALLING NAME : 01617985039 LANGUAGE : en USERNAME : phonefromhere-201 FORMAT : 2 FORMAT2 : gsm CAPABILITY : 2 CAPABILITY2 : gsm ADSICPE : 2 DATE TIME : 2010-12-08 19:09:26 CALLTOKEN : 59 bytes lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] USERNAME : phonefromhere-201 AUTHMETHODS : 6 CHALLENGE : \x37\x41\x45\x31\x39\x45\x39\x31\x43\x32\x36\x39\x44\x38\x43\x39\x43\x42\x44\x30\x44\x41\x39\x41\x32\x38\x46\x38\x33\x36\x33\x46\x38\x39\x42\x43\x41\x46\x46\x37\x43\x36\x35\x41\x39\x34\x38\x36\x35\x31\x38\x37\x38\x37\x46\x37\x46\x32\x42\x41\x39\x43\x43\x46 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP Timestamp: 00110ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] MD5 RESULT : b9e8c31811af122afc20cd1ea1360158 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> FORMAT : 2 FORMAT2 : gsm -- Call accepted by 94.2.84.148 (format gsm) -- Format for call is gsm lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00422ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00423ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> -- IAX2/phonefromhere-201-3258 is ringing lock*CLI> Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 003 Type: VOICE Subclass: 2 Timestamp: 00424ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Tx-Frame Retry[001] -- OSeqno: 002 ISeqno: 003 Type: VOICE Subclass: 2 Timestamp: 00424ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> > birgit 3 - __expire_registry peer=phonefromhere-201 > birgit 2 - realtime_update_peer: nowtime=1291835371, peer=phonefromhere-201, ipaddr=94.2.84.148, port=2688, regtime=0 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] CALLTOKEN : 59 bytes lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 05416ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] USERNAME : phonefromhere-201 AUTHMETHODS : 6 lock*CLI> CHALLENGE : \x37\x41\x45\x31\x39\x45\x39\x31\x43\x32\x36\x39\x44\x38\x43\x39\x43\x42\x44\x30\x44\x41\x39\x41\x32\x38\x46\x38\x33\x36\x33\x46\x38\x39\x42\x43\x41\x46\x46\x37\x43\x36\x35\x41\x39\x34\x38\x36\x35\x31\x38\x37\x38\x37\x46\x37\x46\x32\x42\x41\x39\x43\x43\x46 lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 05418ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] FORMAT : 2 FORMAT2 : gsm lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 05421ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 05424ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: CONTROL Subclass: ANSWER Timestamp: 00000ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 07662ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> -- IAX2/phonefromhere-201-3258 answered SIP/ipcentrex-artemis-in-00000000 lock*CLI> -- Stopped music on hold on SIP/ipcentrex-artemis-in-00000000 lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 004 Type: CONTROL Subclass: (26?) Timestamp: 07665ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835373, regseconds=0, diff=1291835373, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835373, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 004 Type: VOICE Subclass: 2 Timestamp: 00062ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 004 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 00062ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 005 Type: IAX Subclass: LAGRQ Timestamp: 10014ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 005 Type: IAX Subclass: LAGRP Timestamp: 10014ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 006 Type: IAX Subclass: ACK Timestamp: 10014ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 005 Type: IAX Subclass: HANGUP Timestamp: 05937ms SCall: 00004 DCall: 03258 [94.2.84.148:2688] CAUSE : Normal Clearing CAUSE CODE : 16 lock*CLI> Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 007 Type: IAX Subclass: ACK Timestamp: 05937ms SCall: 03258 DCall: 00004 [94.2.84.148:2688] lock*CLI> -- Hungup 'IAX2/phonefromhere-201-3258' lock*CLI> == Spawn extension (from-ipcentrex-to-webphone-1, 201, 9) exited non-zero on 'SIP/ipcentrex-artemis-in-00000000' lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835379, regseconds=0, diff=1291835379, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835379, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00001 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00001 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 CALLTOKEN : 51 bytes lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835402, regseconds=0, diff=1291835402, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835402, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835402, regseconds=0, diff=1291835402, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835402, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835402, regseconds=0, diff=1291835402, IAX_DEFAULT_REG_EXPIRE=60 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835402, regseconds=0, diff=1291835402, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835402, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 2 - realtime_update_peer: nowtime=1291835402, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Timestamp: 00005ms SCall: 02827 DCall: 00001 [94.2.84.148:2688] AUTHMETHODS : 2 CHALLENGE : \x31\x33\x34\x32\x39\x35\x35\x30\x31 USERNAME : phonefromhere-201 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00001 DCall: 02827 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 MD5 RESULT : 094219b4a04dd91bd426e3f41fc8a039 lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835402, regseconds=0, diff=1291835402, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835402, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835402, regseconds=0, diff=1291835402, IAX_DEFAULT_REG_EXPIRE=60 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835402, regseconds=0, diff=1291835402, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835402, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 2 - realtime_update_peer: nowtime=1291835402, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 5.1 - update_registry, nowtime=1291835402 > birgit 2 - realtime_update_peer: nowtime=1291835402, peer=phonefromhere-201, ipaddr=94.2.84.148, port=2688, regtime=1291835402 > birgit 6 - update_registry, schedule expire, p->expiry=60, total expire=70000 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK Timestamp: 00060ms SCall: 02827 DCall: 00001 [94.2.84.148:2688] USERNAME : phonefromhere-201 DATE TIME : 2010-12-08 19:10:02 REFRESH : 60 APPARENT ADDRES : IPV4 94.2.84.148:2688 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00001 DCall: 02827 [94.2.84.148:2688] lock*CLI> > birgit 3 - __expire_registry peer=phonefromhere-201 > birgit 2 - realtime_update_peer: nowtime=1291835422, peer=phonefromhere-201, ipaddr=94.2.84.148, port=2688, regtime=0 lock*CLI> == Using SIP RTP CoS mark 5 lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:1] Set("SIP/ipcentrex-artemis-in-00000001", "GROUP()=applets") in new stack lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:2] GotoIf("SIP/ipcentrex-artemis-in-00000001", "1?5") in new stack lock*CLI> -- Goto (from-ipcentrex-to-webphone-1,201,5) lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:5] Wait("SIP/ipcentrex-artemis-in-00000001", "1") in new stack lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:6] Answer("SIP/ipcentrex-artemis-in-00000001", "") in new stack lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:7] NoOp("SIP/ipcentrex-artemis-in-00000001", "201") in new stack -- Executing [201@from-ipcentrex-to-webphone-1:8] NoOp("SIP/ipcentrex-artemis-in-00000001", ""01617985039" <01617985039>") in new stack lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:9] Dial("SIP/ipcentrex-artemis-in-00000001", "IAX2/phonefromhere-201/201,20,m") in new stack lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835431, regseconds=0, diff=1291835431, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835431, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> [Dec 8 19:10:31] WARNING[16578]: app_dial.c:2039 dial_exec_full: Unable to create channel of type 'IAX2' (cause 20 - Unknown) lock*CLI> == Everyone is busy/congested at this time (1:0/0/1) lock*CLI> -- Executing [201@from-ipcentrex-to-webphone-1:10] Hangup("SIP/ipcentrex-artemis-in-00000001", "") in new stack lock*CLI> == Spawn extension (from-ipcentrex-to-webphone-1, 201, 10) exited non-zero on 'SIP/ipcentrex-artemis-in-00000001' lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREL Timestamp: 00000ms SCall: 00002 DCall: 00000 [94.2.84.148:2688] USERNAME : phonefromhere-201 lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835442, regseconds=0, diff=1291835442, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835442, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> [Dec 8 19:10:42] ERROR[16505]: chan_iax2.c:4912 handle_call_token: Call rejected, CallToken Support required. If unexpected, resolve by placing address 94.2.84.148 in the calltokenoptional list or setting user phonefromhere-201 requirecalltoken=no lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00005 DCall: 00000 [94.2.84.148:2688] REFRESH : 60 USERNAME : phonefromhere-201 lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00000ms SCall: 00002 DCall: 00001 [94.2.84.148:2688] lock*CLI> Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Timestamp: 00000ms SCall: 00002 DCall: 00000 [94.2.84.148:2688] REFRESH : 0 USERNAME : phonefromhere-201 CALLTOKEN : 51 bytes lock*CLI> > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835442, regseconds=0, diff=1291835442, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835442, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835442, regseconds=0, diff=1291835442, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835442, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835442, regseconds=0, diff=1291835442, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835442, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 > birgit 1.0 - realtime_peer, peer=phonefromhere-201, regseconds=0 > birgit 1.1 - realtime_peer, peer=phonefromhere-201, nowtime=1291835442, regseconds=0, diff=1291835442, IAX_DEFAULT_REG_EXPIRE=60 > birgit 2 - realtime_update_peer: nowtime=1291835442, peer=phonefromhere-201, ipaddr=(null), port=0, regtime=0 lock*CLI> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Timestamp: 00004ms SCall: 01090 DCall: 00002 [94.2.84.148:2688] AUTHMETHODS : 2 CHALLENGE : \x31\x37\x38\x38\x33\x32\x31\x35\x32 USERNAME : phonefromhere-201 lock*CLI> Tx-Frame Retry[001] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Timestamp: 00004ms SCall: 01090 DCall: 00002 [94.2.84.148:2688] AUTHMETHODS : 2 CHALLENGE : \x31\x37\x38\x38\x33\x32\x31\x35\x32 USERNAME : phonefromhere-201 lock*CLI> Timestamp: 09985ms SCall: 01090 DCall: 00002 [94.2.84.148:2688] lock*CLI> Tx-Frame Retry[002] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Timestamp: 00004ms SCall: 01090 DCall: 00002 [94.2.84.148:2688] AUTHMETHODS : 2 CHALLENGE : \x31\x37\x38\x38\x33\x32\x31\x35\x32 USERNAME : phonefromhere-201