文珠川慧久の道楽帳2

turezure naru mama ni

NTTコミュニケーションズ050plusの標準度合いを調査しました

2017-09-18 | VoIP
1 概要

050plus は VoIP (Voice Over IP) とSIP (Session Initiation Protocol) を使ってインターネット上で音声通話をするNTTコミュニケーションズ社が提供するサービス(だと思います)です。従って、いわゆる汎用SIPクライアントソフトウェア(アプリ)にパラメタを設定してやれば、NTTコミュニケーションズ社が提供する050plus専用アプリでなくてもサービスを使うことができます。

これこそ国際標準プロトコル技術仕様の存在価値です。国際標準で規定している部分は互換性が確保されます。その上で、電話としての機能性や使い勝手という付加価値で市場競争を行うことによって、きちんとシグナリングして通話ができた上で利用者ユースケースに合ったSIPクライアントアプリを自由に選べる状況ができるというわけです。例えば、NTTコミュニケーションズ社の純正050plusアプリは着信音(マナーモードのときにはバイブ)が1回しか鳴らないという上位層仕様になっていますが、Acrobits Softphoneアプリは普通に鳴り続けてくれるという上位層仕様になっています。どちらのアプリもVoIP及びSIPが国際標準規格に適合ならば後は上位層(操作性など)の味付けの違いで自分の使い勝手に合ったアプリを選べばよいというわけ。

050plusサービスは、VoIPとSIPを使って提供されているサービスなのかどうか、確かめてみることにしました。SIPクライアントとして iPhone5 (iOS 9.1) で Acrobits Softphone (バージョン 5.8 build BD4CF) を使いました。iPhone 6 Plus (iOS 10.3.3) で Acrobits Softphone (バージョン5.8.6 64 bit build E3B4F) を使った時も同じでした。

Acrobits Softphone is the leading SIP client for iPhone, iPad and iPod Touch. Set it up with a VoIP provider of your choice and start making cheap calls over WiFi or 3G from your mobile device. You can also receive VoIP incoming calls anywhere using our Push Notification service or iOS 4 background mode.
http://www.acrobits.net


2 050plus用設定パラメタの取得

先ず手始めは Acrobits Softphone アプリに設定するべき 050plus サービス用のパラメタの取得です。以下を sip.html という名前のテキストファイルに保存してダブルクリックするとブラウザ(MacOSXの場合ならSafari)が開きます。



sip.htmlをブラウザで開いた画面に050plusの電話番号及びパスワードを入力してから一番下にある情報取得ボタンを押しますと画面が切り替わり画面に情報が1行で表示されます。その画面をテキストとしてそのまま保存すると InitSet.aspx.xml というファイル名のテキストになります。その内容は次の通りです。




3 Acrobits Softphoneアプリの設定

上記2で取得した情報から下記の項目を拾い上げて Acrobits Softphone アプリに設定します。
ユーザ名(nicNm):fmc12349999
SIPアカウント(sipID):ABCDEFGH
SIPパスワード(sipPwd):PASSWORD
SIPプロキシサーバアドレス(tranGwAd):kar2-f2fcp.050plus.com
承認されたユーザー名[Auth User Name](sipID):ABCDEFGH
プロキシ[Proxy](tranGwAd):kar2-f2fcp.050plus.com
トランスポートプロトコル[Transport Protocol]:tls(sip)を選ぶ
着信:プッシュで
3Gで利用可能なコーデック:G.729a(←これを購入しましたが無くてもOK)
-----
シングル登録 [Single Instance]:オフ→オン
通話中の登録をブロックする[Block Registration During Call]:オフ→オン
キープアライブ時間[Keepalive Period]:30→60
期限:600→3600(レジストリー反映期限。サーバーはこの値を利用します)
NAT通過:利用しない→利用する
 ホスト:kar2-f2fcp.050plus.com
 トランスポートプロトコル[Transport Protocol]:tls(sip)を選ぶ
安全な通話:着信:利用する
安全な通話:発信:不可→ベストエフォート


4 実験結果

発信:特に問題ない模様です。
着信:プッシュで確実に着信します特に問題ない模様です。
通話:特に問題ない模様です。
切断:問題有りました。

Xperia X Perfomance (au SIM, 090番号) から発信
     ↓
iPhone 5 (Y!mobileデータ通信専用SIM, Acrobits Softphone: 050plus に設定) に着信
     ↓
相互に音声通話可能であることを確認
     ↓
iPhone側で電話を切る
     ↓
Xperia側も自動的に切れなければなりませんが切れません
     ↓
1分か1分半程経過してからやっとXperia側も切れました


5 分析

着信側が電話を切ったのに発信側の電話が切れないというのは、普通ではないと思います。発信側が気付かなければ、この1分か1分半は課金される可能性が高いと考えられます。この状況について、Acrobits Softphone アプリに備わっている通信ログ機能を使って取得したログを Acrobits 社に送って診てもらいました。

Acrobits社の担当者の見立ては次の通りです。Acrobits Softphone が BYE を送信すると 050plus (SIPサーバ) がなぜか接続を切断してしまっているのが問題の原因なのではないかとの疑いがあります。この場面では、TLS接続を切断すべきではなくて BYE への応答を行うべき状況です。即ちAcrobits Softphoneアプリの側ではなくて 050plus (SIPサーバ) 側の問題によって不都合が生じているという整理が一般論だと考える。この場面でTLS接続を切断する理由を示してもらえれば Acrobits Softphone アプリ側で対応できるかもしれません。

さて、少々穿ち過ぎかもわかりませんが、050plus (SIPサーバ) にこのような仕掛けをしておくことで、「BYEに応答する代わりにTLS接続の切断をしてしまう」ことで、およそ1分か1分半の課金を余分にしてやろうというセコイ考えなのかしらん?

Acrobits Softphone 側で終話したら相手側もその直後に切断されねば困るのにそうならないという現象は、2012年にはすでに発覚していたことをネットの掲示板で見つけましたので下に引用します。

| 2012/07/07(土) 00:39:30.15
| 公式アプリ使うよりAcrobits & G711 u-Lawで使う方が音質いい
| ただし、Acrobitsからの終話で、相手側も切断されれば完璧なんだが...
|
| 2012/07/07(土) 00:57:31.62
| Acrobits使ってるけど相手側はこっちが切断したら相手側は切断されないの?
|
| 2012/07/07(土) 08:25:27.19
| Acrobitsから固定、ドコモケータイ、050plus公式アプリ宛へ
| それぞれにかけてみましたが、全ての回線において、
| こちらが切ると相手側は、無音状態の通話中が続きます。
|
| 2012/07/07(土) 13:25:44.49
| 今やったが、終話できたけど
|
| 2012/07/07(土) 19:20:11.80
| 大袈裟に聞いてしまいますが、10回位試して全てOKですか?
| こちらは成功率0%のもので。
| 良かったら、設定内容を欲しいです。

これの後を受けて設定内容をやり取りした形跡は見つかりませんでしたので、やはり Acrobits Softphone 側で終話しても相手側がその直後に切断されないというのは2012年には発覚しているにもかかわらず、5年経過した今でも解決されぬままという整理になると思います。


6 評価

050plusサービスはVoIP及びSIP仕様を採用していますので、いわゆる汎用SIPアプリを使ってサービスを利用できますが、接続(呼)を切断する際のプロトコルだけが国際標準に適合していない。だから電話を掛けた側で1分か1分半程余分に課金されてしまう危険性がある。それでもよければ Acrobits Softphone で 050plus サービスを利用できると言って良さそうだ。


7 参考

(1) 自己満足備忘録(2014-03-09)「050plus」のSIP情報取得〜固定電話として使うまで

(2) acrobits softphoneでFusion IP-Phoneと050plusを使う(2013-02-08)

(3) (一社)日本ネットワークインフォメーションセンター JPNICのホームページから以下に一部抜粋:

SIPの基本

SIPを規定するRFC3261は、269ページに及ぶ非常に厚みのあるRFCです。それだけ、膨大な規定が必要になるということを示しています。ここでは、そのすべてをご紹介することはできませんので、概要を述べるにとどめます。SIPは、端末(User Agent:UA)間でセッションの生成、変更、切断を行うのみのプロトコルで、セッション上で交換されるデータそのものについては定めていません。従って、アプリケーションが、SIPによって制御されたセッション上で、音声のやりとりを行えばIP電話、音声と映像ならばテレビ電話、テキストメッセージならばインスタントメッセンジャーというように幅広い応用が可能となります。次に、例としてAliceがBobへIP電話をかける場合を想定して、そのセッションの過程を概観します。ここで出てくる機器は、AliceとBobのIP電話機(=UA)、各IP電話機の収容するSIPプロキシサーバA(atlanta.com)とB(biloxi.com)です。SIPプロキシサーバは、公衆電話交換網で言うならば、交換機のようなもので、UAやプロキシからのリクエストを受け取り、適切なUA、プロキシへ送信を行います。通話開始から終了までにおけるやりとりは、図1のようになります。


図1: IP電話セッション確立から切断までの例(参考:RFC3261)

セッションの確立は、INVITE(招待)メッセージ送信から始まります。SIPにおけるUAの識別は、sip:alice@atlanta.com、sip:bob@biloxi.comのようにURI(Uniform Resource Identifier)形式で行い、AliceはBobとのセッション確立のために、sip:bob@biloxi.comへINVITEメッセージを送信します(図1の左上の方にある(1)INVITE)。


図2は、AliceからBobへのINVITEメッセージの例です。

このメッセージ形式からわかるように、SIPではアスキーで記述されているため、メッセージの内容を可読できます。そして、形式がHTTPやSMTPに似ているため、容易に内容を理解できます。INVITEを受信したプロキシAでは、宛先が、bob@ biloxi.comであることから、biloxi.comのプロキシBへINVITEメッセージを送信します(図1の(2))。また、プロキシAは、Aliceへ「プロキシBへのINVITEを実行中である」ことを通知する暫定応答100Tryingを送信します(図1の(3))。この「100」とは、要求に対する結果を示すステータスコードで、図3に示すようにHTTPで定めたステータスコードを拡張した仕様となっています。


図3: ステータスコード

プロキシBは、受信したINVITE(図1の(2))から、配下のBobへINVITEを送信します(図1の(4))。INVITEを受信したBobは、電話のベルを鳴らすなど相手からの呼び出し処理を行い、併せて、発信元(Alice)へ呼び出し中であることを伝えるため、暫定応答180RingingをプロキシBへ応答し、180 Ringingは、Aliceへ転送されます(図1の(6)と(7)と(8))。Bobは、受話器のオフフック(受話器をあげる)などによって、成功200OKをプロキシB/Aを経由してAに送信します(図1の(9)と(10)と(11))。Aliceは、Bobからの200OKを元にACK応答(セッション確立了解)をBobへ送信し(図1の(12))、AliceとBobの間にセッションが生成されます。生成されたセッション上で、音声データがやりとりされ、通話状態となります。そして、Bob上の受話器がオンフックとなったとき、BYE要求(セッション切断要求)と200OK応答によって、セッションが終了し、通話が終了します(図1の(13)と(14))。以上に簡単ではありますが、SIPの概要を説明致しました。SIPのシグナリングは、シンプルで、また、HTTPやSMTPに似ていることから、理解しやすく、実装もしやすいことがお分かりいただけたかと思います。今回紹介したのは、SIPのほんの一部の部分です。このほかにも、様々な仕組みが存在します。興味のある方は、RFCなど文献をご参照ください。

SIPと相互接続性

昨今、企業内でのVoIP化や、ブロードバンドの普及に伴うコンシューマー市場におけるVoIP化によって、種々のVoIP端末(UA)が登場してきています。その普及に従って、VoIP機器同士の相互接続性が問題となっています。一般的に考えて、IETFが標準化したプロトコルであるSIPを実装したIP電話やサーバ(VoIP交換機)間ならば、相互接続が可能であると考えるでしょう。しかしながら、現状、残念なことに相互接続がうまくいかない場合があります。VoIP機器は、登場当初から、閉じたシステムである傾向が強かったため、サーバ(VoIP交換機)とIP電話機がセットで開発され、独自拡張などが施される場合もあります。また、ベンダー毎にURIの表記方法が異なっていたり、RFCが厳密に定義していない点などが影響して、SIPに対応した製品同士やVoIP事業者同士の相互接続が保証できていません。この問題点を解決するために、規格面・実装面からの相互接続性の実現に向けての活動が行われています。規格面では、(社)情報通信技術委員会(TTC)が、相互接続に必要な仕様の策定をしています。実装面では、(社)テレコムサービス協会 VoIP推進協議会 相互接続作業班や高度通信システム相互接続推進会議において、ベンダー間(端末とサーバ)の相互接続性の検証が行われており、VoIP/SIP相互接続検証タスクフォースにおいては、VoIP事業者間における相互接続の検証が進められています。
各所における相互接続検証活動を通して、問題は解決方向にむかっていっているといえます。

NTTコミュニケーションズ050plusの標準度合いを調査しましたlog06

2017-09-18 | VoIP
2017-09-14T13:06:24.901Z (1505394384901126)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Connection state: Connected
Not Connected Reason: -
Error: -

2017-09-14T13:06:24.901Z (1505394384901395)
ali::network::sip::layer::transport::stream_channel::do_send
Sending message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 520
SIP/2.0 481 Call Does Not Exist
Via: SIP/2.0/TLS 60.37.58.171:5061;branch=z9hG4bK589ae649
Via: SIP/2.0/UDP 60.37.58.132:6061;branch=z9hG4bK68af2664
Contact:
From: ;tag=76d9aa9b
Call-ID: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
CSeq: 2 BYE
To: ;tag=46E90C412A52051A33F71E1145AD99D3
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
Content-Length: 0


2017-09-14T13:06:24.901Z (1505394384901596)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Exit Message Queue Size: 0

2017-09-14T13:06:26.391Z
we are awake...
getCurrentState: return state:Registered
getCurrentState: return state:Registered

NTTコミュニケーションズ050plusの標準度合いを調査しましたlog05

2017-09-18 | VoIP
<Body><PendingCalls/><IncomingCalls/><PendingTextMessages Count="0"/></Body>
UdpClient::LinkBase: request id rGRKPQaH is no longer valid
2017-09-14T13:06:14.109Z (1505394374109579)
ali::network::sip::layer::transport::stream_channel::conn_can_read
Received message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 395
SIP/2.0 423 Interval Too Brief
Via: SIP/2.0/TLS 10.221.82.222:54159;received=126.200.121.75;branch=z9hG4bKlLgYb2NQ4Kqw0S36;rport=31473
From: ;tag=9B4501B976764D03E460F9FBD6986703
To: ;tag=6d5fffb7
Call-ID: 45A195F5104CA0B269496BC6E563A7720DD074AE
CSeq: 1114 REGISTER
Min-Expires: 3600
Content-Length: 0



2017-09-14T13:06:14.109Z (1505394374109884)
ali::network::sip::transaction::CtransTrying
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 5

2017-09-14T13:06:14.109Z (1505394374109975)
ali::network::sip::transaction::CtransTerminate
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 5

2017-09-14T13:06:14.110Z (1505394374110224)
Sip::Registration::onResponse
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
State: Registering
Code: 423 Interval Too Brief

2017-09-14T13:06:14.110Z (1505394374110315)
Sip::Registration::writeRegisterRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
State: Registering

2017-09-14T13:06:14.110Z (1505394374110473)
Sip::Shared::startGlobalContactDiscovery
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Purpose: Global Contact Discovery
Use Global Contact: No
Global Contact State: Off

2017-09-14T13:06:14.110Z (1505394374110536)
Sip::Shared::writeClientRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Request Id: 399183664
Discovering Global Contact: No

2017-09-14T13:06:14.110Z (1505394374110830)
ali::network::sip::layer::transport::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]
Host Name:
Host: kar-f2fcp.050plus.com
Port:
Transport: tls+sip:

2017-09-14T13:06:14.110Z (1505394374110922)
ali::network::sip::layer::transport::channel::send
Message queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Queue Size: 1

2017-09-14T13:06:14.111Z (1505394374111025)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Entry Message Queue Size: 1

2017-09-14T13:06:14.111Z (1505394374111105)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Connection state: Connected
Not Connected Reason: -
Error: -

2017-09-14T13:06:14.111Z (1505394374111347)
Sip::Registration::registerRequestPreSend
Registering with the following contact:
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Contact: <Contact>
<Uri user="fmc12345678" scheme="sip" host="10.221.82.222" port="54159">
<Params rinstance="D6E514F1" transport="tls"/>
</Uri>
<Params expires="3600" video=""/>
</Contact>

2017-09-14T13:06:14.111Z (1505394374111721)
ali::network::sip::layer::transport::stream_channel::do_send
Sending message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 709
REGISTER sip:kar2-f2fcp.050plus.com SIP/2.0
Via: SIP/2.0/TLS 10.221.82.222:54159;branch=z9hG4bK6zsdyNRa6mNKBcA7;rport
Route:
Route:
Contact: ;expires=3600;video
Max-Forwards: 70
From: ;tag=D03A07C264390F570B5F7CFA24767CD6
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
User-Agent: Acrobits Softphone/5.8
To:
Expires: 3600
Call-ID: 45A195F5104CA0B269496BC6E563A7720DD074AE
CSeq: 1115 REGISTER
Content-Length: 0


2017-09-14T13:06:14.112Z (1505394374112083)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Exit Message Queue Size: 0

2017-09-14T13:06:14.112Z (1505394374112299)
ali::network::sip::transaction::start_client_transaction
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 6

2017-09-14T13:06:14.177Z (1505394374177815)
ali::network::sip::layer::transport::stream_channel::conn_can_read
Received message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 492
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TLS 10.221.82.222:54159;received=126.200.121.75;branch=z9hG4bK6zsdyNRa6mNKBcA7;rport=31473
From: ;tag=D03A07C264390F570B5F7CFA24767CD6
To:
Call-ID: 45A195F5104CA0B269496BC6E563A7720DD074AE
CSeq: 1115 REGISTER
WWW-Authenticate: Digest realm="com-voip.jp", domain="sip:153.128.252.228", nonce="1505392785", opaque="", stale=FALSE, algorithm=MD5
Content-Length: 0



2017-09-14T13:06:14.178Z (1505394374178143)
ali::network::sip::transaction::CtransTrying
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 6

2017-09-14T13:06:14.178Z (1505394374178233)
ali::network::sip::transaction::CtransTerminate
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 6

2017-09-14T13:06:14.178Z (1505394374178463)
Preparing for authentication using the Digest method.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipDigest[385015120]
Realm: com-voip.jp
Nonce: 1505392785
QOP:
Opaque:
Domain: sip:153.128.252.228

2017-09-14T13:06:14.178Z (1505394374178799)
ali::network::sip::layer::transport::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]
Host Name:
Host: kar-f2fcp.050plus.com
Port:
Transport: tls+sip:

2017-09-14T13:06:14.178Z (1505394374178893)
ali::network::sip::layer::transport::channel::send
Message queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Queue Size: 1

2017-09-14T13:06:14.178Z (1505394374178975)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Entry Message Queue Size: 1

2017-09-14T13:06:14.179Z (1505394374179051)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Connection state: Connected
Not Connected Reason: -
Error: -

2017-09-14T13:06:14.179Z (1505394374179446)
ali::network::sip::layer::transport::stream_channel::do_send
Sending message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 882
REGISTER sip:kar2-f2fcp.050plus.com SIP/2.0
Via: SIP/2.0/TLS 10.221.82.222:54159;branch=z9hG4bKXcsnHFAHB6RTo8B9;rport
Route:
Route:
Contact: ;expires=3600;video
Max-Forwards: 70
From: ;tag=D03A07C264390F570B5F7CFA24767CD6
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
User-Agent: Acrobits Softphone/5.8
To:
Expires: 3600
Call-ID: 45A195F5104CA0B269496BC6E563A7720DD074AE
CSeq: 1116 REGISTER
Authorization: Digest username="PQRSTUVW",realm="com-voip.jp",algorithm=MD5,uri="sip:kar2-f2fcp.050plus.com",nonce="1505392785",response="f4198592d150e83e85f49595c5cd3c13"
Content-Length: 0


2017-09-14T13:06:14.179Z (1505394374179717)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Exit Message Queue Size: 0

2017-09-14T13:06:14.179Z (1505394374179929)
ali::network::sip::transaction::start_client_transaction
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 7

2017-09-14T13:06:14.251Z (1505394374251511)
ali::network::sip::layer::transport::stream_channel::conn_can_read
Received message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 527
SIP/2.0 200 OK
Via: SIP/2.0/TLS 10.221.82.222:54159;received=126.200.121.75;branch=z9hG4bKXcsnHFAHB6RTo8B9;rport=31473
From: ;tag=D03A07C264390F570B5F7CFA24767CD6
To:
Call-ID: 45A195F5104CA0B269496BC6E563A7720DD074AE
CSeq: 1116 REGISTER
Contact: ;expires=35774
Contact: ;expires=3600
Expires: 3600
Content-Length: 0



2017-09-14T13:06:14.251Z (1505394374251955)
ali::network::sip::transaction::CtransTrying
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 7

2017-09-14T13:06:14.252Z (1505394374252085)
ali::network::sip::transaction::CtransTerminate
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 7

2017-09-14T13:06:14.252Z (1505394374252511)
Sip::Registration::onResponse
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
State: Registering
Code: 200 OK

2017-09-14T13:06:14.252Z (1505394374252637)
Sip::Registration::setState
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Current State: Registering
Next State: Registered

getCurrentState: return state:Registered
REGSTATECHANGED, current=Registered
getCurrentRealState: return state:Registered
AgentWithSipis::updateSipisAgent() - updating account
AgentWithSipis::updateSipisAgent() - same accounts, update not needed
UdpRequestProcessor
sending
2017-09-14T13:06:14.256Z
UdpClient sending 542 bytes:
REGISTERED
Version: 2.0
RequestId: R2z4KaSF
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Checksum: 651ED5D0BF586D277912C95914FE714E31D438E6
Expires: 60
Method: tunnel
Device-Token: lpkxPV276A9R45WmkCOL91/fhAMTLNtetzwBL8XtQIE=
App-Id: cz.acrobits.softphone.own.pushkit
Authorization: Digest username="fmc12345678",realm="sipis10 a",nonce="59ba7e765184e674",uri="B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F",qop=auth,cnonce="dc45f7cb7702a77b83ee7e87e0bd2080",nc=00000005,response="9f58abdb139f71490745b3ab35cafd85",algorithm=MD5


getCurrentState: return state:Registered
2017-09-14T13:06:14.256Z
onRegistrationStateChanged, accountId=029Ma8Yama6azTmI
getCurrentState: return state:Registered
getCurrentState: return state:Registered
2017-09-14T13:06:14.484Z
UdpClient::LinkBase onDataReceived
2017-09-14T13:06:14.484Z
UdpClient::LinkBase received:
REGISTERED-ACK
Content-Length: 76
Expires: 60
Method: Tunnel
RequestId: R2z4KaSF
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Server: sipis10 a
Timestamp: 1505394374

<Body><PendingCalls/><IncomingCalls/><PendingTextMessages Count="0"/></Body>
2017-09-14T13:06:14.484Z
SIPIS onLocallyRegisteredResponse: REGISTERED-ACK
onSipisResult with result Success
2017-09-14T13:06:24.899Z (1505394384899775)
ali::network::sip::layer::transport::stream_channel::conn_can_read
Received message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 419
BYE sip:fmc12345678@10.221.82.222:54159;transport=tls SIP/2.0
Via: SIP/2.0/TLS 60.37.58.171:5061;branch=z9hG4bK589ae649, SIP/2.0/UDP 60.37.58.132:6061;branch=z9hG4bK68af2664
From: ;tag=76d9aa9b
To: ;tag=46E90C412A52051A33F71E1145AD99D3
Call-ID: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
CSeq: 2 BYE
Max-Forwards: 69
Content-Length: 0



2017-09-14T13:06:24.900Z (1505394384900614)
Sip::Shared::requestHandler
The request didn't match any existing dialog.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Dialog Id: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba8583046e90c412a52051a33f71e1145ad99d376d9aa9b
Existing Dialog Ids: (None)

2017-09-14T13:06:24.900Z (1505394384900770)
Sip::Shared::startGlobalContactDiscovery
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Purpose: Global Contact Discovery
Use Global Contact: No
Global Contact State: Off

2017-09-14T13:06:24.900Z (1505394384900903)
ali::network::sip::layer::transport::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]
Host Name: kar-f2fcp.050plus.com
Host: 60.37.58.171
Port: 5061
Transport: tls+sip:

2017-09-14T13:06:24.900Z (1505394384900979)
ali::network::sip::layer::transport::channel::send
Message queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Queue Size: 1

2017-09-14T13:06:24.901Z (1505394384901054)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Entry Message Queue Size: 1

NTTコミュニケーションズ050plusの標準度合いを調査しましたlog04

2017-09-18 | VoIP
attemptAutoAnswerForPush
VU
start, status=2
BRIDGE
updateFeeds
Setting call feeder
VU
start, status=2
Account dump:
----
id:029Ma8Yama6azTmI
username:fmc12345678
auth username:PQRSTUVW
domain:kar2-f2fcp.050plus.com:0
proxyServer:kar2-f2fcp.050plus.com:5061
proxyIP:0.0.0.0
natTraversalMode:auto
stunServer:stun.acrobits.cz
stunUser:
contactIP:internal
codecOrderWiFi:0,18,8,9,103,102,3
codecOrder3G:18,0,103,102,3,8,9
vCodecOrderWiFi:99,34
vCodecOrder3G:99,34
transport:tls+sip:
listen:
sel:B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
voicemailNumber:
mustUnregister:1
codecTimes:20,30,no,no
honorTheirCodecList:1,0
outbound proxy:kar-f2fcp.050plus.com:0
keepAlive:1, period=60
incoming calls = push, global:push
pushMethod = tunnel
----
UdpRequestProcessor
sending
2017-09-14T13:06:12.713Z
UdpClient sending 542 bytes:
REGISTERED
Version: 2.0
RequestId: rGRKPQaH
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Checksum: 651ED5D0BF586D277912C95914FE714E31D438E6
Expires: 60
Method: tunnel
Device-Token: lpkxPV276A9R45WmkCOL91/fhAMTLNtetzwBL8XtQIE=
App-Id: cz.acrobits.softphone.own.pushkit
Authorization: Digest username="fmc12345678",realm="sipis10 a",nonce="59ba7e765184e674",uri="B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F",qop=auth,cnonce="b0825842e96de7f731ad09a6fb5dfaf3",nc=00000004,response="314b4b425ae35b009191f265e1fda63d",algorithm=MD5


2017-09-14T13:06:12.764Z
BRIDGE CONTROLLER CHANGES MODE
prev=PlayAndRecord, new=PlayMedia
desired sampling rate is 44100, current sampling rate is 16000
new HW sampling rate is 44100
VU
dispose, status=2
VU
stop, status=2
VUP:53699, 0/0
VU
stop END, status=1
VU
dispose END, status=0
2017-09-14T13:06:13.433Z
Category changing from AVAudioSessionCategoryPlayAndRecord (0) to AVAudioSessionCategoryPlayback (1)
2017-09-14T13:06:13.709Z
BRIDGE
resetVoiceUnit, dsr=44100
HW sampling rate is 44100, HW buffer duration is 23 ms
VU
initialize, status=0
VU
initialize END, status=1
VU restart took 17 ms
VU restart took 709 ms
2017-09-14T13:06:13.726Z
Mode changed
BRIDGE
updateFeeds
Setting call feeder
VU
start, status=1
VU
start END, status=2, delay=1088
VUP:36663, 99/99
UdpRequestProcessor
sending
2017-09-14T13:06:13.875Z
UdpClient sending 542 bytes:
REGISTERED
Version: 2.0
RequestId: rGRKPQaH
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Checksum: 651ED5D0BF586D277912C95914FE714E31D438E6
Expires: 60
Method: tunnel
Device-Token: lpkxPV276A9R45WmkCOL91/fhAMTLNtetzwBL8XtQIE=
App-Id: cz.acrobits.softphone.own.pushkit
Authorization: Digest username="fmc12345678",realm="sipis10 a",nonce="59ba7e765184e674",uri="B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F",qop=auth,cnonce="b0825842e96de7f731ad09a6fb5dfaf3",nc=00000004,response="314b4b425ae35b009191f265e1fda63d",algorithm=MD5


2017-09-14T13:06:13.876Z (1505394373876818)
ali::network::basic_communicator2::socket_state_changed
Socket Id: 7
Socket State: Connected

2017-09-14T13:06:13.915Z
UdpClient::LinkBase onDataReceived
2017-09-14T13:06:13.915Z
UdpClient::LinkBase received:
REGISTERED-ACK
Content-Length: 76
Expires: 60
Method: Tunnel
RequestId: rGRKPQaH
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Server: sipis10 a
Timestamp: 1505394372

<Body><PendingCalls/><IncomingCalls/><PendingTextMessages Count="0"/></Body>
2017-09-14T13:06:13.915Z
SIPIS onLocallyRegisteredResponse: REGISTERED-ACK
onSipisResult with result Success
2017-09-14T13:06:13.919Z (1505394373919745)
ali::network::sip::layer::transport::stream_channel::conn_state_changed
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Connected To: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]

2017-09-14T13:06:13.919Z (1505394373919914)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Entry Message Queue Size: 1

2017-09-14T13:06:13.919Z (1505394373919997)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Connection state: Connected
Not Connected Reason: -
Error: -

2017-09-14T13:06:13.920Z (1505394373920331)
ali::network::sip::layer::transport::stream_channel::do_send
Sending message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Local Address: 10.221.82.222:54161
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Message Size: 631
BYE sip:60.37.58.132:6061;transport=udp SIP/2.0
Via: SIP/2.0/TCP 10.221.82.222:54161;branch=z9hG4bKYLD8EGzPZvU87vGU;rport
Route:
Contact:
Max-Forwards: 70
From: ;tag=46E90C412A52051A33F71E1145AD99D3
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
User-Agent: Acrobits Softphone/5.8
To: ;tag=76d9aa9b
Call-ID: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
CSeq: 1 BYE
Content-Length: 0


2017-09-14T13:06:13.920Z (1505394373920790)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Exit Message Queue Size: 0

2017-09-14T13:06:13.921Z (1505394373921607)
Sip::Shared::transportChannelAddressesChanged
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Destinations: kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061
Local Address: 10.221.82.222:54161
Mapped Address: (unspecified)
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061

getCurrentState: return state:Registered
getCurrentState: return state:Registered
2017-09-14T13:06:14.041Z (1505394374041229)
ali::network::basic_communicator2::socket_state_changed
Socket Id: 7
Socket State: Disconnected

2017-09-14T13:06:14.041Z (1505394374041354)
ali::network::sip::layer::transport::stream_channel::conn_state_changed
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Not Connected To: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Reason: Disconnected by Peer
Error: -

2017-09-14T13:06:14.041Z (1505394374041473)
ali::network::sip::layer::transport::channel::post_error
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Error: SIP(3): Transport Disconnected
In ali_net_sip_layer_transport_channel.cpp:1257

2017-09-14T13:06:14.044Z (1505394374044411)
ali::network::sip::transaction::CtransTerminate
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: BYE
Id: 4

2017-09-14T13:06:14.044Z (1505394374044834)
Sip::Shared::cancelClientRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Request Id: 397487256

2017-09-14T13:06:14.044Z (1505394374044898)
Sip::Shared::failDelayedRequests
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Global Contact State: Off
Number of Delayed Messages: 0
Number of Delayed Responses: 0
Number of Delayed Requests: 0+0

2017-09-14T13:06:14.045Z (1505394374045028)
Sip::Registration::onTransportError
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
State: Registered
Error: SIP(3): Transport Disconnected
In ali_net_sip_layer_transport_channel.cpp:1257

2017-09-14T13:06:14.045Z (1505394374045102)
Sip::Registration::onTransportError
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
State: Registered
Retry: Yes
Error: SIP(3): Transport Disconnected
In ali_net_sip_layer_transport_channel.cpp:1257

2017-09-14T13:06:14.045Z (1505394374045167)
Sip::Registration::privateReset
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]

2017-09-14T13:06:14.045Z (1505394374045212)
Sip::Registration::setState
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Current State: Registered
Next State: NotRegistered

2017-09-14T13:06:14.045Z (1505394374045314)
Sip::Shared::cancelClientRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Request Id: 399183664

2017-09-14T13:06:14.045Z (1505394374045396)
Sip::Registration::setState
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Current State: NotRegistered
Next State: Registering

2017-09-14T13:06:14.045Z (1505394374045455)
Sip::Registration::writeRegisterRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
State: Registering

2017-09-14T13:06:14.045Z (1505394374045619)
Sip::Shared::startGlobalContactDiscovery
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Purpose: Global Contact Discovery
Use Global Contact: No
Global Contact State: Off

2017-09-14T13:06:14.045Z (1505394374045683)
Sip::Shared::writeClientRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Request Id: 399183664
Discovering Global Contact: No

2017-09-14T13:06:14.045Z (1505394374045984)
ali::network::sip::layer::transport::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]
Host Name:
Host: kar-f2fcp.050plus.com
Port:
Transport: tls+sip:

2017-09-14T13:06:14.046Z (1505394374046081)
ali::network::sip::layer::transport::channel::send
Message queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Queue Size: 1

2017-09-14T13:06:14.046Z (1505394374046166)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Entry Message Queue Size: 1

2017-09-14T13:06:14.046Z (1505394374046243)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Connection state: Connected
Not Connected Reason: -
Error: -

2017-09-14T13:06:14.046Z (1505394374046594)
Sip::Registration::registerRequestPreSend
Registering with the following contact:
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Contact: <Contact>
<Uri user="fmc12345678" scheme="sip" host="10.221.82.222" port="54159">
<Params rinstance="D6E514F1" transport="tls"/>
</Uri>
<Params expires="600" video=""/>
</Contact>

2017-09-14T13:06:14.047Z (1505394374047088)
ali::network::sip::layer::transport::stream_channel::do_send
Sending message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 707
REGISTER sip:kar2-f2fcp.050plus.com SIP/2.0
Via: SIP/2.0/TLS 10.221.82.222:54159;branch=z9hG4bKlLgYb2NQ4Kqw0S36;rport
Route:
Route:
Contact: ;expires=600;video
Max-Forwards: 70
From: ;tag=9B4501B976764D03E460F9FBD6986703
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
User-Agent: Acrobits Softphone/5.8
To:
Expires: 600
Call-ID: 45A195F5104CA0B269496BC6E563A7720DD074AE
CSeq: 1114 REGISTER
Content-Length: 0


2017-09-14T13:06:14.047Z (1505394374047703)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Exit Message Queue Size: 0

2017-09-14T13:06:14.049Z (1505394374049179)
ali::network::sip::transaction::start_client_transaction
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: REGISTER
Id: 5

getCurrentState: return state:Registering
REGSTATECHANGED, current=Registering
getCurrentRealState: return state:Registering
getCurrentState: return state:Registering
2017-09-14T13:06:14.054Z
onRegistrationStateChanged, accountId=029Ma8Yama6azTmI
getCurrentState: return state:Registering
getCurrentState: return state:Registering
2017-09-14T13:06:14.090Z
UdpClient::LinkBase onDataReceived
2017-09-14T13:06:14.090Z
UdpClient::LinkBase received:
REGISTERED-ACK
Content-Length: 76
Expires: 60
Method: Tunnel
RequestId: rGRKPQaH
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Server: sipis10 a
Timestamp: 1505394373

NTTコミュニケーションズ050plusの標準度合いを調査しましたlog03

2017-09-18 | VoIP
attemptAutoAnswerForPush
2017-09-14T13:06:03.875Z
Sending RTP packet #2 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=809271CC36C27B4BC4DB85F345318B9D928CD7C0F23EF86B7B4CACD2C6FFCC91
JITTER: audio=0ms, net=0ms, pktloss=0%
VU
start, status=2
BRIDGE
updateFeeds
Setting call feeder
VU
start, status=2
2017-09-14T13:06:03.907Z
Sending RTP packet #3 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=809271CD36C27C3BC4DB85F3FB3DBC610E0C0142FFEA75720CF1C67EEF05BD5D
2017-09-14T13:06:03.939Z
Sending RTP packet #4 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271CE36C27D2BC4DB85F3B181BDEA55E12009DBFA8C30E0BB5681F0B4B5AC
2017-09-14T13:06:03.971Z
Sending RTP packet #5 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271CF36C27E1BC4DB85F30E526760A8B76B9ACDCCC7352079F1EB7B460F5D
2017-09-14T13:06:04.002Z
Sending RTP packet #6 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D036C27F0BC4DB85F36D6BF0FB2EF6FCC30AD5437381D8BDC4B00ECA9A
2017-09-14T13:06:04.034Z
Sending RTP packet #7 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D136C27FFBC4DB85F319CB013C1B2763507332AD34471F421F3E2507D9
VU
start, status=2
BRIDGE
updateFeeds
Setting call feeder
VU
start, status=2
2017-09-14T13:06:04.051Z
Sending RTP packet #8 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D236C280EBC4DB85F3D676AD61C73BB88D1EE407CE8C0FAF367D70C80C
2017-09-14T13:06:04.083Z
Sending RTP packet #9 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D336C281DBC4DB85F3FA578C543D8F1493BDDE5B20525648D35DC030A6
2017-09-14T13:06:04.115Z
Sending RTP packet #10 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D436C282CBC4DB85F32CCC2980E49826A73A7B8C6A782847B7320D46A0
2017-09-14T13:06:04.147Z
Sending RTP packet #11 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D536C283BBC4DB85F334DC837AE45F2D79F2402694521C4F4670F1FF6C
2017-09-14T13:06:04.179Z
Sending RTP packet #12 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D636C284ABC4DB85F35A26BD926F33386DA0708EA9A1B650757087A5DE
2017-09-14T13:06:04.211Z
Sending RTP packet #13 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D736C2859BC4DB85F3B4050066FE49B0078ECF3D15659E490372D87142
2017-09-14T13:06:04.226Z
Received RTP packet #1 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=8012007E00009D80807EF5A03EE60393D37C697A4826A8C81F187274DFCE3501
2017-09-14T13:06:04.243Z
Sending RTP packet #14 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=801271D836C2868BC4DB85F3B02F6B436A3B38F49514F3390F0A71B2FA8FDDAA
2017-09-14T13:06:04.306Z
Received RTP packet #2 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=8012007F00009EC0807EF5A03361D5ADC7F4AAC700EE861975B6F9B3E60B5ED8
2017-09-14T13:06:04.320Z (1505394364320493)
Rtp::Session::updateMediaStatus
Media status changed.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]
Audio Incoming: Active; 18
Audio Outgoing: Active; 18
Video Incoming: Inactive; -
Video Outgoing: Inactive; -

VU
start, status=2
BRIDGE
updateFeeds
Setting call feeder
VU
start, status=2
2017-09-14T13:06:04.346Z
Received RTP packet #3 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200800000A000807EF5A084F6C4136328EA417D15AE05C310C6A89929D13E
2017-09-14T13:06:04.395Z (1505394364395702)
ali::network::sip::layer::transport::stream_channel::conn_can_read
Received message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 419
ACK sip:fmc12345678@10.221.82.222:54159;transport=tls SIP/2.0
Via: SIP/2.0/TLS 60.37.58.171:5061;branch=z9hG4bK7bcaf1e8, SIP/2.0/UDP 60.37.58.132:6061;branch=z9hG4bK4faadce1
From: ;tag=76d9aa9b
To: ;tag=46E90C412A52051A33F71E1145AD99D3
Call-ID: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
CSeq: 1 ACK
Max-Forwards: 69
Content-Length: 0



2017-09-14T13:06:04.396Z (1505394364396242)
Sip::Shared::requestHandler
The request matches an existing dialog.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Status: Alive
Call Id: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
Local Tag: 46E90C412A52051A33F71E1145AD99D3
Remote Tag: 76d9aa9b
Local SeqNo: 1
Remote SeqNo: 1
Local URI: <From uri="sip:05012345678@voip.ntt.com">
<Uri scheme="sip" user="05012345678" host="voip.ntt.com"/>
<Params tag="46E90C412A52051A33F71E1145AD99D3"/>
</From>
Remote URI: <To uri="sip:08080019999@voip.ntt.com">
<Uri scheme="sip" user="08080019999" host="voip.ntt.com"/>
<Params tag="76d9aa9b"/>
</To>
Remote Target: <Contact uri="sip:60.37.58.132:6061;transport=udp">
<Uri scheme="sip" host="60.37.58.132" port="6061">
<Params transport="udp"/>
</Uri>
</Contact>
Route Set:
<Route
uri="sip:nxs_38f7ed5d_flow=03bb4fd137b438fb@60.37.58.171:5061;transport=tcp;lr">
<Uri
scheme="sip"
user="nxs_38f7ed5d_flow=03bb4fd137b438fb"
host="60.37.58.171"
port="5061"
host-name="kar-f2fcp.050plus.com">
<Params transport="tcp" lr=""/>
</Uri>
</Route>


2017-09-14T13:06:04.396Z (1505394364396678)
Sip::Dialog::writeServerRequest
Dialog request queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Request Id: 0
Method: ACK
Queue Size: 1
Notifying: Yes

2017-09-14T13:06:04.396Z (1505394364396869)
Sip::Dialog::readServerRequest
Dialog request dispatched.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Request Id: 0
Method: ACK

2017-09-14T13:06:04.396Z (1505394364396933)
Sip::Dialog::notifyServerRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Invariant Holds: Yes
Queue Size: 0

2017-09-14T13:06:04.397Z (1505394364397001)
Sip::Call::serverRequestHandler
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]SiphoneUserAgent[399182336]SipUserAgent[397486592]SipCall[391365632]
Request Id: 0
Method: ACK

2017-09-14T13:06:04.426Z
Received RTP packet #4 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200810000A140807EF5A04E11CB1C0D832E44E3E672E262E38CA5827B26EF
2017-09-14T13:06:04.466Z
Received RTP packet #5 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200820000A280807EF5A0B2A135BF166D5401625D1D42468A644080F58DCF
2017-09-14T13:06:04.507Z
Received RTP packet #6 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200830000A3C0807EF5A046EA3A44A8190724B5AFB4F1D5D9BCA8C4405DF8
2017-09-14T13:06:04.548Z
Received RTP packet #7 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200840000A500807EF5A0BA8C2872FFEF863549D5141F26CEBC7217991E50
2017-09-14T13:06:04.587Z
Received RTP packet #8 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200850000A640807EF5A03BC14B8FDAFC03ACB28C5F0E721031AF49CEBE17
2017-09-14T13:06:04.626Z
Received RTP packet #9 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200860000A780807EF5A0E780D361F12AB5A8A6C4E095849EFB54E393F2BF
2017-09-14T13:06:04.666Z
Received RTP packet #10 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200870000A8C0807EF5A0E3FBC8F805D40C04DC92DD4F0F6324E766C67B66
2017-09-14T13:06:04.706Z
Received RTP packet #11 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200880000AA00807EF5A06FE61F233C150031BA7CD08EB8C997856D8A3A83
2017-09-14T13:06:04.720Z (1505394364720668)
Rtp::Session::MediaStream::startOrUpdateNetwork
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390289024]
ICE Check List State: Running
All Comps Have Valid Pair: No

2017-09-14T13:06:04.720Z (1505394364720920)
Rtp::Session::MediaStream::startOrUpdateNetwork
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390289024]
Available Local Addresses: RTP (SDP): 10.221.82.222:12648
RTCP (SDP): 10.221.82.222:12649
RTP (ICE): (unspecified)
RTCP (ICE): (unspecified)
Available Remote Addresses: RTP (SDP): 60.37.58.164:62088
RTCP (SDP): 60.37.58.164:62089
RTP (ICE): (unspecified)
RTCP (ICE): (unspecified)
RTP (Inc): 60.37.58.164:62088
RTCP (Inc): (unspecified)
Use Incoming: Yes

2017-09-14T13:06:04.721Z (1505394364721382)
Rtp::Session::MediaStream::startOrUpdateNetwork
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390289024]
Selected Local Addresses: RTP: 10.221.82.222:12648; was 10.221.82.222:12648
RTCP: 10.221.82.222:12649; was 10.221.82.222:12649
Selected Remote Addresses: RTP: 60.37.58.164:62088; was 60.37.58.164:62088
RTCP: 60.37.58.164:62089; was 60.37.58.164:62089

2017-09-14T13:06:04.747Z
Received RTP packet #12 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200890000AB40807EF5A0568A5A108C937DDFA0AA0DFFE571A9970DDB16CE
2017-09-14T13:06:04.787Z
Received RTP packet #13 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=8012008A0000AC80807EF5A0C9B66CB4B6B358C58833FAE650CDAFA01D590C2E
2017-09-14T13:06:04.828Z
Received RTP packet #14 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=8012008B0000ADC0807EF5A094C7842C67C447BCD2C790253473AC46742EA342
JITTER: audio=5ms, net=0ms, pktloss=0%
JITTER: audio=5ms, net=2ms, pktloss=0%
2017-09-14T13:06:06.820Z
Sending RTP packet #100 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=8012722E36C2D72BC4DB85F37E3D110DE5FE543D7F87715D322D53F413BF6324
JITTER: audio=5ms, net=2ms, pktloss=0%
JB parameters: span=0, optimum=240, count=0, lostSeq=(0,0)
JITTER: audio=5ms, net=2ms, pktloss=0%
2017-09-14T13:06:08.266Z
Received RTP packet #100 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=801200E100011940807EF5A0ADC4E03B4FB515243E8C2DBDBA337030D20CB446
JITTER: audio=5ms, net=2ms, pktloss=0%
2017-09-14T13:06:09.812Z
Sending RTP packet #200 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=8012729236C334EBC4DB85F3B1C43D879B1A543B99E896594B2B3115E5FCC707
JITTER: audio=5ms, net=2ms, pktloss=0%
2017-09-14T13:06:10.183Z
Sending RTP packet #2 10.221.82.222:12649 > 60.37.58.164:62089, len=94, really=94, data=81C8000CC4DB85F3AAA3123EF14938B82BB40FE64FF954491BB35FBB18ED7130
UdpRequestProcessor
sending
2017-09-14T13:06:10.796Z
UdpClient sending 542 bytes:
REGISTERED
Version: 2.0
RequestId: q2i67Kci
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Checksum: 651ED5D0BF586D277912C95914FE714E31D438E6
Expires: 60
Method: tunnel
Device-Token: lpkxPV276A9R45WmkCOL91/fhAMTLNtetzwBL8XtQIE=
App-Id: cz.acrobits.softphone.own.pushkit
Authorization: Digest username="fmc12345678",realm="sipis10 a",nonce="59ba7e765184e674",uri="B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F",qop=auth,cnonce="7457da2ee51ac9487632219c16ce9a24",nc=00000003,response="ab7f1b50d0d9ffa5d07de1392939d327",algorithm=MD5


JITTER: audio=5ms, net=2ms, pktloss=0%
2017-09-14T13:06:11.021Z
UdpClient::LinkBase onDataReceived
2017-09-14T13:06:11.022Z
UdpClient::LinkBase received:
REGISTERED-ACK
Content-Length: 76
Expires: 60
Method: Tunnel
RequestId: q2i67Kci
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Server: sipis10 a
Timestamp: 1505394370

<Body><PendingCalls/><IncomingCalls/><PendingTextMessages Count="0"/></Body>
2017-09-14T13:06:11.022Z
SIPIS onLocallyRegisteredResponse: REGISTERED-ACK
onSipisResult with result Success
JB parameters: span=0, optimum=240, count=0, lostSeq=(0,0)
JITTER: audio=5ms, net=2ms, pktloss=0%
2017-09-14T13:06:12.268Z
Received RTP packet #200 60.37.58.164:62088 > 10.221.82.222:12648, len=62, data=8012014500019640807EF5A0EEC52899CF199F775045192C61CF0EB60217100E
resetting call from Siphone::hangup
2017-09-14T13:06:12.644Z (1505394372644527)
Call::~Call
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]
State: Established
Rtp State: NotInTransaction
Call State: Established

2017-09-14T13:06:12.644Z (1505394372644618)
Call<Sip>::~Call
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]
Active Call Count: 0

2017-09-14T13:06:12.645Z (1505394372645699)
Sip::Shared::startGlobalContactDiscovery
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Purpose: Global Contact Discovery
Use Global Contact: No
Global Contact State: Off

2017-09-14T13:06:12.646Z (1505394372646182)
ali::network::sip::layer::transport::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]
Host Name: kar-f2fcp.050plus.com
Host: 60.37.58.171
Port: 5061
Transport: tcp

2017-09-14T13:06:12.646Z (1505394372646287)
ali::network::sip::layer::transport::create_channel
Initiating a new channel.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]

2017-09-14T13:06:12.646Z (1505394372646422)
ali::network::sip::layer::transport::channel::send
Message queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Queue Size: 1

2017-09-14T13:06:12.646Z (1505394372646525)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Entry Message Queue Size: 1

2017-09-14T13:06:12.646Z (1505394372646615)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Connection state: Not Connected
Not Connected Reason: Disconnected
Error: -

2017-09-14T13:06:12.646Z (1505394372646770)
ali::network::basic_communicator2::connect
Socket Id: 7
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061

2017-09-14T13:06:12.646Z (1505394372646830)
ali::network::basic_communicator2::connect
Connecting socket.
Socket Id: 7
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061

2017-09-14T13:06:12.655Z (1505394372655435)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]
Exit Message Queue Size: 1

2017-09-14T13:06:12.655Z (1505394372655593)
ali::network::sip::transaction::start_client_transaction
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]
Method: BYE
Id: 4

2017-09-14T13:06:12.655Z (1505394372655842)
Rtp::Session::resetMediaStream
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:12.655Z (1505394372655906)
Rtp::Session::resetMediaStream
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:12.655Z
RTP socket 31 shutdown
2017-09-14T13:06:12.656Z
RTP socket 31 close
RTP UdpSocket: select failed, errno=9. Terminating socket thread
2017-09-14T13:06:12.656Z
RTP socket 31 shutdown
2017-09-14T13:06:12.656Z
RTP socket 31 close
2017-09-14T13:06:12.656Z
RTP socket 31 destroyed
2017-09-14T13:06:12.656Z
RTP socket -1 destroyed
2017-09-14T13:06:12.656Z
RTP socket 34 shutdown
2017-09-14T13:06:12.656Z
RTP socket 34 close
RTP UdpSocket: select failed, errno=9. Terminating socket thread
2017-09-14T13:06:12.656Z
RTP socket 34 shutdown
2017-09-14T13:06:12.656Z
RTP socket 34 close
2017-09-14T13:06:12.656Z
RTP socket 34 destroyed
2017-09-14T13:06:12.657Z
RTP socket -1 destroyed
updateCallEventForStateTransition: Established -> Terminated
2017-09-14T13:06:12.676Z (1505394372676697)
ali::network::sip::layer::transport::stream_channel::conn_state_changed
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403146288]
Connecting To: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com/60.37.58.171:5061; 60.37.58.171:5061]

2017-09-14T13:06:12.677Z (1505394372677884)
ali::network::basic_communicator2::socket_state_changed
Socket Id: 7
Socket State: Connecting

NTTコミュニケーションズ050plusの標準度合いを調査しましたlog02

2017-09-18 | VoIP
filterAndSortInterfaces hints: signalling local: 10.221.82.222, remote: kar-f2fcp.050plus.com, fallback: 8.8.8.8
filterAndSortInterfaces, proxyOutgoingInterface = 10.221.82.222
2017-09-14T13:06:03.777Z
### NETWORKS ###
pdp_ip0 (10.221.82.222 Cellular up,point-to-point,multicast)

created RTP socket 34 for iface pdp_ip0 - sockCount=0
2017-09-14T13:06:03.777Z (1505394363777179)
Rtp::Private::UdpSocket::setState
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[405542240]
Current State: Initializing
Next State: Ready

2017-09-14T13:06:03.777Z (1505394363777251)
Rtp::Private::UdpSocket::resetAndGatherCandidates
Started gathering local candidate addresses.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[385668528]
Mode: Disabled
STUN/TURN Server Address: (unspecified)

2017-09-14T13:06:03.779Z (1505394363779367)
Rtp::Private::UdpSocket::resetAndGatherCandidates
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[385668528]
Discovered Local Addresses: pdp_ip0 (10.221.82.222 Cellular up,point-to-point,multicast)[10.221.82.222:12648@pdp_ip0]

2017-09-14T13:06:03.780Z (1505394363780250)
Rtp::Private::UdpSocket::advanceState
Finished the address gathering process.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[385668528]

2017-09-14T13:06:03.780Z (1505394363780376)
Rtp::Private::UdpSocket::resetAndGatherCandidates
Started gathering local candidate addresses.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[405542240]
Mode: Disabled
STUN/TURN Server Address: (unspecified)

2017-09-14T13:06:03.780Z (1505394363780492)
Rtp::Private::UdpSocket::resetAndGatherCandidates
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[405542240]
Discovered Local Addresses: pdp_ip0 (10.221.82.222 Cellular up,point-to-point,multicast)[10.221.82.222:12649@pdp_ip0]

2017-09-14T13:06:03.780Z (1505394363780583)
Rtp::Private::UdpSocket::advanceState
Finished the address gathering process.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[405542240]

2017-09-14T13:06:03.780Z (1505394363780669)
Call::rtpStateChanged
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]
State: Trying
Rtp State: PreparingOurAnswer
Call State: null

RTP BRIDGE CONTROLLER
2017-09-14T13:06:03.789Z
Setting call audio route to 0 (automatically)

RTP BRIDGE CONTROLLER
2017-09-14T13:06:03.792Z
Setting call audio route to 0 (automatically)

2017-09-14T13:06:03.794Z (1505394363794640)
Rtp::Session::someNetworkChanged
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:03.802Z (1505394363802334)
Rtp::Session::someNetworkChanged
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:03.802Z (1505394363802480)
Rtp::Session::finishOurAnswer
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:03.803Z (1505394363803038)
Rtp::Session::processTheirSessionDescription
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]
State: PreparingOurAnswer
Audio Network Type: ThreeG
Video Network Type: nullptr
Our SDP: v=0
o=- 1179050671 29025 IN IP4 172.26.170.170
s=jvicvoo
t=0 0
Their SDP: v=0
o=- 1505394357 1505394357 IN IP4 60.37.58.164
s=-
c=IN IP4 60.37.58.164
t=0 0
m=audio 62088 RTP/SAVP 18 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=rtpmap:18 G729/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:kXn8Yv3RMr2Y2WlqKN9N2U3jc+SGhntg8bV3PxMK
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:EWRR3vteb4V5FbLPlbc5+q34N151TtJOfSk0SiMq

2017-09-14T13:06:03.811Z (1505394363811973)
Rtp::Session::processTheirSessionDescription
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]
Our Exit SDP: v=0
o=- 1179050671 29025 IN IP4 172.26.170.170
s=jvicvoo
t=0 0
m=audio 12648 RTP/SAVP 18 101
c=IN IP4 10.221.82.222
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:nKUxcn7oeQM3aCREWCiUEvHhceHmkAsWrngfKevm
a=ptime:30

2017-09-14T13:06:03.812Z (1505394363812916)
Not going to use ICE.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]
Reason: ICE is disabled on local side.

2017-09-14T13:06:03.813Z (1505394363813286)
Not going to use Legacy-ICE.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]
Reason: ICE is disabled on local side.

2017-09-14T13:06:03.814Z (1505394363814082)
Rtp::Session::updateAudioStream
Chosen outgoing audio format.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]
Media Index: 0
Payload Number: 18
Payload Name: G729/8000

2017-09-14T13:06:03.823Z (1505394363823244)
Rtp::Session::MediaStream::startOrUpdateNetwork
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390289024]
ICE Check List State: Running
All Comps Have Valid Pair: No

2017-09-14T13:06:03.824Z (1505394363824832)
Rtp::Session::MediaStream::startOrUpdateNetwork
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390289024]
Available Local Addresses: RTP (SDP): 10.221.82.222:12648
RTCP (SDP): 10.221.82.222:12649
RTP (ICE): (unspecified)
RTCP (ICE): (unspecified)
Available Remote Addresses: RTP (SDP): 60.37.58.164:62088
RTCP (SDP): 60.37.58.164:62089
RTP (ICE): (unspecified)
RTCP (ICE): (unspecified)
RTP (Inc): (unspecified)
RTCP (Inc): (unspecified)
Use Incoming: Yes

2017-09-14T13:06:03.825Z (1505394363825106)
Rtp::Session::MediaStream::startOrUpdateNetwork
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390289024]
Selected Local Addresses: RTP: 10.221.82.222:12648; was (unspecified)
RTCP: 10.221.82.222:12649; was (unspecified)
Selected Remote Addresses: RTP: 60.37.58.164:62088; was (unspecified)
RTCP: 60.37.58.164:62089; was (unspecified)

2017-09-14T13:06:03.828Z (1505394363828378)
Rtp::Private::UdpSocket::start
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[385668528]
Local Address: 10.221.82.222:12648
Remote Address: 60.37.58.164:62088

2017-09-14T13:06:03.828Z (1505394363828492)
Rtp::Private::UdpSocket::setState
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[385668528]
Current State: Ready
Next State: Started

2017-09-14T13:06:03.828Z (1505394363828567)
Rtp::Private::UdpSocket::update
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[385668528]
Local Address: 10.221.82.222:12648
Remote Address: 60.37.58.164:62088

2017-09-14T13:06:03.828Z (1505394363828638)
Rtp::Private::UdpSocket::update
Selected Internal Addresses:
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[385668528]
Local Address: 10.221.82.222:12648
Remote Address: 60.37.58.164:62088

2017-09-14T13:06:03.828Z
UDPSocketSetStart
2017-09-14T13:06:03.828Z (1505394363828800)
Rtp::Private::UdpSocket::start
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[405542240]
Local Address: 10.221.82.222:12649
Remote Address: 60.37.58.164:62089

2017-09-14T13:06:03.828Z (1505394363828872)
Rtp::Private::UdpSocket::setState
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[405542240]
Current State: Ready
Next State: Started

2017-09-14T13:06:03.828Z (1505394363828939)
Rtp::Private::UdpSocket::update
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[405542240]
Local Address: 10.221.82.222:12649
Remote Address: 60.37.58.164:62089

2017-09-14T13:06:03.829Z (1505394363829011)
Rtp::Private::UdpSocket::update
Selected Internal Addresses:
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[405542240]
Local Address: 10.221.82.222:12649
Remote Address: 60.37.58.164:62089

2017-09-14T13:06:03.829Z
UDPSocketSetStart
2017-09-14T13:06:03.831Z
Sending RTP packet #1 10.221.82.222:12649 > 60.37.58.164:62089, len=50, really=50, data=80C90001C4DB85F3E4EF17C44C808035B2447BA428014A46BAF26ED83A0A98B8
2017-09-14T13:06:03.831Z (1505394363831647)
Rtp::Session::stopVideoStream
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:03.831Z (1505394363831786)
Rtp::Session::stopTextMessageStream
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:03.831Z (1505394363831936)
Rtp::Session::updateMediaStatus
Media status changed.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]
Audio Incoming: Active; -
Audio Outgoing: Active; 18
Video Incoming: Inactive; -
Video Outgoing: Inactive; -

2017-09-14T13:06:03.832Z (1505394363832158)
Rtp::Session::updateSecurityStatus
Security status changed.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:03.832Z (1505394363832428)
Rtp::Session::updateNatTraversalStatus
NAT traversal status changed.
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]
Local Address: pdp_ip0 (10.221.82.222 Cellular up,point-to-point,multicast)[10.221.82.222:12648@pdp_ip0]
Detected NAT: No
Detected Symmetric NAT: No
Using Relay Server: No
Relay Server Address: (unspecified)

2017-09-14T13:06:03.832Z (1505394363832634)
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:03.833Z (1505394363833598)
Call::rtpStateChanged
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]
State: Trying
Rtp State: OurAnswerReady
Call State: null

2017-09-14T13:06:03.833Z (1505394363833788)
Call::setState
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]
State: Established
Rtp State: OurAnswerReady
Call State: null

2017-09-14T13:06:03.834Z (1505394363834387)
Sip::Dialog::writeServerResponse
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Request Id: 1

2017-09-14T13:06:03.834Z (1505394363834483)
ali::network::sip::layer::transport::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]
Host Name: kar-f2fcp.050plus.com
Host: 60.37.58.171
Port: 5061
Transport: tls+sip:

2017-09-14T13:06:03.834Z (1505394363834563)
ali::network::sip::layer::transport::channel::send
Message queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Queue Size: 1

2017-09-14T13:06:03.834Z (1505394363834644)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Entry Message Queue Size: 1

2017-09-14T13:06:03.834Z (1505394363834719)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Connection state: Connected
Not Connected Reason: -
Error: -

2017-09-14T13:06:03.835Z (1505394363835030)
ali::network::sip::layer::transport::stream_channel::do_send
Sending message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 933
SIP/2.0 200 OK
Via: SIP/2.0/TLS 60.37.58.171:5061;branch=z9hG4bK3f658e03
Via: SIP/2.0/UDP 60.37.58.132:6061;branch=z9hG4bK53d7a971
Record-Route:
Contact:
From: ;tag=76d9aa9b
Call-ID: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
CSeq: 1 INVITE
To: ;tag=46E90C412A52051A33F71E1145AD99D3
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
Content-Type: application/sdp
Content-Length: 303

v=0
o=- 1179050671 29026 IN IP4 172.26.170.170
s=jvicvoo
c=IN IP4 10.221.82.222
t=0 0
m=audio 12648 RTP/SAVP 18 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:nKUxcn7oeQM3aCREWCiUEvHhceHmkAsWrngfKevm
a=ptime:30
a=sendrecv

2017-09-14T13:06:03.835Z (1505394363835271)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Exit Message Queue Size: 0

2017-09-14T13:06:03.839Z (1505394363839164)
Sip::Dialog::notifyServerRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Invariant Holds: Yes
Queue Size: 0

2017-09-14T13:06:03.840Z (1505394363840394)
Call::callStateChanged
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]
State: Established
Rtp State: NotInTransaction
Call State: Established

updateCallEventForStateTransition: IncomingTrying -> Established
2017-09-14T13:06:03.843Z
Sending RTP packet #1 10.221.82.222:12648 > 60.37.58.164:62088, len=52, really=52, data=809271CB36C27A5BC4DB85F309BEF3940999D57B4718FC207206FEA52BAE9655
2017-09-14T13:06:03.862Z (1505394363862380)
Call::rtpStateChanged
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]
State: Established
Rtp State: NotInTransaction
Call State: Established

NTTコミュニケーションズ050plusの標準度合いを調査しましたlog01

2017-09-18 | Weblog
Account dump:
----
id:029Ma8Yama6azTmI
username:fmc12345678
auth username:PQRSTUVW
domain:kar2-f2fcp.050plus.com:0
proxyServer:kar2-f2fcp.050plus.com:5061
proxyIP:0.0.0.0
natTraversalMode:auto
stunServer:stun.acrobits.cz
stunUser:
contactIP:internal
codecOrderWiFi:0,18,8,9,103,102,3
codecOrder3G:18,0,103,102,3,8,9
vCodecOrderWiFi:99,34
vCodecOrder3G:99,34
transport:tls+sip:
listen:
sel:B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
voicemailNumber:
mustUnregister:1
codecTimes:20,30,no,no
honorTheirCodecList:1,0
outbound proxy:kar-f2fcp.050plus.com:0
keepAlive:1, period=60
incoming calls = push, global:push
pushMethod = tunnel
----
getCurrentState: return state:Registered
getCurrentState: return state:Registered
2017-09-14T13:05:21.101Z
UdpClient::LinkBase onDataReceived
2017-09-14T13:05:21.101Z
UdpClient::LinkBase received:
REGISTERED-ACK
Content-Length: 76
Expires: 60
Method: Tunnel
RequestId: 6G3HrKyF
Selector: B42A1DEBEBED8AF1A620209034C9BFA247E1FC9F
Server: sipis10 a
Timestamp: 1505394320

<Body><PendingCalls/><IncomingCalls/><PendingTextMessages Count="0"/></Body>
2017-09-14T13:05:21.101Z
SIPIS onLocallyRegisteredResponse: REGISTERED-ACK
onSipisResult with result Success
2017-09-14T13:05:26.389Z
we are awake...
2017-09-14T13:05:58.121Z (1505394358121362)
ali::network::sip::layer::transport::stream_channel::conn_can_read
Received message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 1130
INVITE sip:fmc12345678@10.221.82.222:54159 SIP/2.0
Via: SIP/2.0/TLS 60.37.58.171:5061;branch=z9hG4bK3f658e03, SIP/2.0/UDP 60.37.58.132:6061;branch=z9hG4bK53d7a971
Record-Route:
From: ;tag=76d9aa9b
To:
Call-ID: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
CSeq: 1 INVITE
Max-Forwards: 69
Contact:
Supported: timer
Allow: INVITE,ACK,BYE,CANCEL,UPDATE,PRACK
Session-Expires: 180;refresher=uas
Min-SE: 180
Privacy: none
P-Asserted-Identity:
Content-Type: application/sdp
Content-Length: 406


v=0
o=- 1505394357 1505394357 IN IP4 60.37.58.164
s=-
c=IN IP4 60.37.58.164
t=0 0
m=audio 62088 RTP/SAVP 18 0 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:kXn8Yv3RMr2Y2WlqKN9N2U3jc+SGhntg8bV3PxMK
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:EWRR3vteb4V5FbLPlbc5+q34N151TtJOfSk0SiMq

2017-09-14T13:05:58.126Z (1505394358126435)
ali::network::sip::layer::transport::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]
Host Name: kar-f2fcp.050plus.com
Host: 60.37.58.171
Port: 5061
Transport: tls+sip:

2017-09-14T13:05:58.126Z (1505394358126609)
ali::network::sip::layer::transport::channel::send
Message queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Queue Size: 1

2017-09-14T13:05:58.126Z (1505394358126775)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Entry Message Queue Size: 1

2017-09-14T13:05:58.126Z (1505394358126936)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Connection state: Connected
Not Connected Reason: -
Error: -

2017-09-14T13:05:58.127Z (1505394358127376)
ali::network::sip::layer::transport::stream_channel::do_send
Sending message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 327
SIP/2.0 100 Trying
Via: SIP/2.0/TLS 60.37.58.171:5061;branch=z9hG4bK3f658e03
Via: SIP/2.0/UDP 60.37.58.132:6061;branch=z9hG4bK53d7a971
From: ;tag=76d9aa9b
Call-ID: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
CSeq: 1 INVITE
To:
Content-Length: 0


2017-09-14T13:05:58.127Z (1505394358127744)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Exit Message Queue Size: 0

2017-09-14T13:05:58.128Z (1505394358128961)
Sip::Shared::createDialogFromRequest
Dialog created.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Status: Alive
Call Id: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
Local Tag: 46E90C412A52051A33F71E1145AD99D3
Remote Tag: 76d9aa9b
Local SeqNo: 1
Remote SeqNo: 1
Local URI: <From uri="sip:05012345678@voip.ntt.com">
<Uri scheme="sip" user="05012345678" host="voip.ntt.com"/>
<Params tag="46E90C412A52051A33F71E1145AD99D3"/>
</From>
Remote URI: <To uri="sip:08080019999@voip.ntt.com">
<Uri scheme="sip" user="08080019999" host="voip.ntt.com"/>
<Params tag="76d9aa9b"/>
</To>
Remote Target: <Contact uri="sip:60.37.58.132:6061;transport=udp">
<Uri scheme="sip" host="60.37.58.132" port="6061">
<Params transport="udp"/>
</Uri>
</Contact>
Route Set:
<Route
uri="sip:nxs_38f7ed5d_flow=03bb4fd137b438fb@60.37.58.171:5061;transport=tcp;lr">
<Uri
scheme="sip"
user="nxs_38f7ed5d_flow=03bb4fd137b438fb"
host="60.37.58.171"
port="5061"
host-name="kar-f2fcp.050plus.com">
<Params transport="tcp" lr=""/>
</Uri>
</Route>


2017-09-14T13:05:58.140Z (1505394358140761)
Sip::Dialog::notifyServerRequest
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Invariant Holds: Yes
Queue Size: 0

2017-09-14T13:05:58.173Z (1505394358173216)
Sip::Dialog::writeServerResponse
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]
Request Id: 1

2017-09-14T13:05:58.173Z (1505394358173671)
ali::network::sip::layer::transport::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]
Host Name: kar-f2fcp.050plus.com
Host: 60.37.58.171
Port: 5061
Transport: tls+sip:

2017-09-14T13:05:58.173Z (1505394358173833)
ali::network::sip::layer::transport::channel::send
Message queued.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Queue Size: 1

2017-09-14T13:05:58.174Z (1505394358174001)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Entry Message Queue Size: 1

2017-09-14T13:05:58.174Z (1505394358174184)
ali::network::sip::layer::transport::stream_channel::do_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Connection state: Connected
Not Connected Reason: -
Error: -

2017-09-14T13:05:58.174Z (1505394358174808)
ali::network::sip::layer::transport::stream_channel::do_send
Sending message.
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Local Address: 10.221.82.222:54159
Remote Address: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Message Size: 602
SIP/2.0 180 Ringing
Via: SIP/2.0/TLS 60.37.58.171:5061;branch=z9hG4bK3f658e03
Via: SIP/2.0/UDP 60.37.58.132:6061;branch=z9hG4bK53d7a971
Record-Route:
Contact:
From: ;tag=76d9aa9b
Call-ID: f64a3690fe3b103fbcdb000069e3@10.124.237.107--_6ba85830
CSeq: 1 INVITE
To: ;tag=46E90C412A52051A33F71E1145AD99D3
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
Content-Length: 0


2017-09-14T13:05:58.175Z (1505394358175260)
ali::network::sip::layer::transport::channel::try_send
Correlation Id: SiphoneUserAgent[399182336]SipUserAgent[397486592]SipTransaction[397487796]SipTransport[403772784]SipTransportChannel[403059568]
Destination: kar-f2fcp.050plus.com/60.37.58.171:5061 [kar-f2fcp.050plus.com; 60.37.58.171:5061]
Exit Message Queue Size: 0

updateCallEventForStateTransition: IncomingRinging -> IncomingRinging
updateCallEventForStateTransition: IncomingRinging -> IncomingRinging
attemptAutoAnswerForPush
attemptAutoAnswerForPush - state OK
attemptAutoAnswerForPush - active
attemptAutoAnswerForPush - no screen
desired sampling rate is 48000, current sampling rate is 44100
new HW sampling rate is 48000
BRIDGE
updateFeeds
Setting ring feeder
VU
start, status=2
2017-09-14T13:05:59.854Z
BRIDGE CONTROLLER CHANGES MODE
prev=PlayMedia, new=PlayMedia
VU
dispose, status=2
VU
stop, status=2
VUP:37635, 0/0
VU
stop END, status=1
VU
dispose END, status=0
2017-09-14T13:05:59.867Z
Category changing from AVAudioSessionCategoryPlayback (1) to AVAudioSessionCategoryPlayback (0)
2017-09-14T13:05:59.878Z
BRIDGE
resetVoiceUnit, dsr=48000
HW sampling rate is 48000, HW buffer duration is 21 ms
VU
initialize, status=0
VU
initialize END, status=1
VU restart took 11 ms
VU restart took 35 ms
2017-09-14T13:05:59.890Z
Mode changed
BRIDGE
updateFeeds
Setting ring feeder
VU
start, status=1
VU
start END, status=2, delay=1089
getCurrentState: return state:Registered
getCurrentState: return state:Registered
VUP:37383, 99/99
Siphone::acceptIncoming, mCall=nullptr, groupWith=nullptr
2017-09-14T13:06:01.855Z (1505394361855482)
Call<Sip>::Call
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]
Direction: Incoming
Active Call Count: 1

Siphone::acceptIncoming, got siplib call @174C9A00
updateCallEventForStateTransition: IncomingRinging -> IncomingTrying
attemptAutoAnswerForPush
attemptAutoAnswerForPush - state OK
attemptAutoAnswerForPush - active
attemptAutoAnswerForPush - no screen
VU
start, status=2
BRIDGE
updateFeeds
Setting call feeder
VU
start, status=2
2017-09-14T13:06:02.041Z
BRIDGE CONTROLLER CHANGES MODE
prev=PlayMedia, new=PlayAndRecord
desired sampling rate is 16000, current sampling rate is 48000
new HW sampling rate is 22050
VU
dispose, status=2
VU
stop, status=2
VUP:37383, 0/0
VU
stop END, status=1
VU
dispose END, status=0
2017-09-14T13:06:02.469Z
Category changing from AVAudioSessionCategoryPlayback (0) to AVAudioSessionCategoryPlayAndRecord (0)
2017-09-14T13:06:02.847Z
BRIDGE
resetVoiceUnit, dsr=8000
HW sampling rate is 16000, HW buffer duration is 16 ms
VU
initialize, status=0
VU
initialize END, status=1
VU restart took 700 ms
VU restart took 1113 ms
BRIDGE
startVoiceUnit
VU
start, status=1
VU
start END, status=2, delay=285
BRIDGE
updateFeeds
Setting call feeder
VU
start, status=2
2017-09-14T13:06:03.643Z
Mode changed
BRIDGE
updateFeeds
Setting call feeder
VU
start, status=2
VUP:53699, 99/99
RTP BRIDGE CONTROLLER
2017-09-14T13:06:03.756Z
Setting call audio route to 0 (automatically)

2017-09-14T13:06:03.763Z (1505394363763010)
Rtp::Session::processTheirOffer
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

2017-09-14T13:06:03.764Z (1505394363764823)
Rtp::Session::networkParametersChanged
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]

filterAndSortInterfaces hints: signalling local: 10.221.82.222, remote: kar-f2fcp.050plus.com, fallback: 8.8.8.8
filterAndSortInterfaces, proxyOutgoingInterface = 10.221.82.222
2017-09-14T13:06:03.776Z
### NETWORKS ###
pdp_ip0 (10.221.82.222 Cellular up,point-to-point,multicast)

created RTP socket 31 for iface pdp_ip0 - sockCount=0
2017-09-14T13:06:03.776Z (1505394363776403)
Rtp::Private::UdpSocket::setState
Correlation Id: SiphoneUserAgent[399182336]SiphoneSipCall[390896128]RtpSession[390285824]RtpAudioStream[390286532]RtpNetwork[397974552]RtpUdpSocket[385668528]
Current State: Initializing
Next State: Ready