バックアップ回線での通信ができない

このトラブルシューティングは、以下の機種に対応しています。

対応機種: RTX5000 RTX3500 RTX1210 RTX1200

設定例はこちら

インデックス

症状     状態確認方法     トラブル原因と対処方法

症状:バックアップ回線での通信ができない

ここでは、ルーターの基本的な設定が終了し、バックアップ回線での通信ができないケースでのトラブルシューティングを提供します。

下記の前提で説明します。
・RTX1210の設定値や出力結果をベースに説明します。

ルーターLAN1 IPアドレス
(LAN1ポートを使用)
192.168.0.1
ルーターWAN(PP[01]) IPアドレス
(LAN2ポートを使用)
172.16.1.1
(動的グローバルIPアドレス)
ルーターWAN(PP[02]) IPアドレス 172.16.2.1
(動的グローバルIPアドレス)

状態確認方法

この項目ではコマンドによる状態確認方法を紹介します。表示例は正常動作時のものです。
トラブル発生時の状態と比較することで、問題解決の助けとなります。


1-1 から順に確認してください。

1-1 バックアップの状態を確認する。

ルーターの"show status backup"コマンドを実行し、バックアップの状態を確認します。

[正常時]
(1)
   # show status backup
   INTERFACE   DLCI   BACKUP                         STATE           TIMER
   --------------------------------------------------------------------------
   PP[01]             PP[02]                         master

(2)
   # show status backup

   INTERFACE   DLCI   BACKUP                         STATE           TIMER
   --------------------------------------------------------------------------
   PP[01]             PP[02]                         wait backup     5

   # show status backup

   INTERFACE   DLCI   BACKUP                         STATE           TIMER
   --------------------------------------------------------------------------
   PP[01]             PP[02]                         wait backup     4

   # show status backup

   INTERFACE   DLCI   BACKUP                         STATE           TIMER
   --------------------------------------------------------------------------
   PP[01]             PP[02]                         wait backup     3

   # show status backup

   INTERFACE   DLCI   BACKUP                         STATE           TIMER
   --------------------------------------------------------------------------
   PP[01]             PP[02]                         wait backup     2

   # show status backup

   INTERFACE   DLCI   BACKUP                         STATE           TIMER
   --------------------------------------------------------------------------
   PP[01]             PP[02]                         wait backup     1

(3)
   # show status backup

   INTERFACE   DLCI   BACKUP                         STATE           TIMER
   --------------------------------------------------------------------------
   PP[01]             PP[02]                         backup
[解説]
メイン回線からバックアップ回線への切り替わりの際に以下のように変化します。

(1)メイン回線が接続されている時の状態です。
(2)メイン回線が切断され、バックアップ回線に切り替わるまでの状態です。
(3)バックアップ回線に切り替わった時の状態です。

1-2 NATディスクリプタの状態を確認する。

ルーターの"show nat descriptor address"コマンドを実行し、内容を確認します。

[正常時]
(1)
   # show nat descriptor address
   NAT/IPマスカレード 動作タイプ : 2
   参照NATディスクリプタ : 1, 適用インタフェース : PP[01](1)
   Masqueradeテーブル
       外側アドレス: ipcp/172.16.1.1
       ポート範囲: 60000-64095, 49152-59999, 44096-49151
   ---------------------
   参照NATディスクリプタ : 1, 適用インタフェース : PP[02](1)
   Masqueradeテーブル
       外側アドレス: ipcp
       ポート範囲: 60000-64095, 49152-59999, 44096-49151
   ---------------------
   有効なNATディスクリプタテーブルが2個ありました

(2)
   # show nat descriptor address
   NAT/IPマスカレード 動作タイプ : 2
   参照NATディスクリプタ : 1, 適用インタフェース : PP[01](1)
   Masqueradeテーブル
       外側アドレス: ipcp
       ポート範囲: 60000-64095, 49152-59999, 44096-49151
   ---------------------
   参照NATディスクリプタ : 1, 適用インタフェース : PP[02](1)
   Masqueradeテーブル
       外側アドレス: ipcp
       ポート範囲: 60000-64095, 49152-59999, 44096-49151
   ---------------------
   有効なNATディスクリプタテーブルが2個ありました

(3)
   # show nat descriptor address
   NAT/IPマスカレード 動作タイプ : 2
   参照NATディスクリプタ : 1, 適用インタフェース : PP[01](1)
   Masqueradeテーブル
       外側アドレス: ipcp
       ポート範囲: 60000-64095, 49152-59999, 44096-49151
   ---------------------
   参照NATディスクリプタ : 1, 適用インタフェース : PP[02](1)
   Masqueradeテーブル
       外側アドレス: ipcp/172.16.2.1
       ポート範囲: 60000-64095, 49152-59999, 44096-49151   3 セッション
     -*-    -*-    -*-    -*-    -*-    -*-    -*-    -*-    -*-    -*-    -*-
         No.       内側アドレス    セッション数  ホスト毎制限数         種別
          1         192.168.0.1               3           65534         dynamic
   ---------------------
   有効なNATディスクリプタテーブルが2個ありました
[解説]
(1)メイン回線接続時の状態です。
PP[01]側ではIPアドレス172.16.1.1を取得しています。
バックアップ回線は接続していませんので、PP[02]側ではIPアドレスは取得していません。
 
(2)障害によりメイン回線が切断された時の状態です。バックアップ回線は接続されていません。
PP[01]、PP[02]側共にIPアドレスを取得していません。
 
(3)メイン回線が切断され、バックアップ回線を接続した時の状態です。
PP[01]側ではIPアドレスを取得していません。PP[02]側ではIPアドレス172.16.2.1を取得しています。

1-3 経路情報を確認する。

ルーターの"show ip route"コマンドを実行し、経路情報を確認します。

[正常時]
(1)
   # show ip route
   宛先ネットワーク    ゲートウェイ     インタフェース  種別  付加情報
   default             -                    PP[01]    static
   192.168.0.0/24      192.168.0.1            LAN1  implicit
   172.16.1.2/32       -                    PP[01] temporary

(2)
   # show ip route
   宛先ネットワーク    ゲートウェイ     インタフェース  種別  付加情報
   default             -                    PP[01]    static
   192.168.0.0/24      192.168.0.1            LAN1  implicit

(3)
   # show ip route
   宛先ネットワーク    ゲートウェイ     インタフェース  種別  付加情報
   default             -                    PP[01]    static
   172.16.2.2/32       -                    PP[02] temporary
   192.168.0.0/24      192.168.0.1            LAN1  implicit
[解説]
(1)メイン回線接続時の状態です。
プロバイダ側機器のIPアドレス172.16.1.2が表示されています。
 
(2)メイン回線が切断された時の状態です。バックアップ回線は接続されていません。
コマンドで設定されたデフォルト経路のみが表示されています。
 
(3)メイン回線が切断され、バックアップ回線を接続した時の状態です。
プロバイダ側機器のIPアドレス172.16.2.2が表示されています。

1-4 デバッグログを確認する。

回線の切断・接続後、ルーターで"show log"コマンドを実行すると以下のようなログが表示されます。
※ログ採集の前にルーターに"syslog debug on"を設定しておきます。

[バックアップ切り替わり成功時ログの例]
# show log
2015/04/23 13:47:04: LAN2: link down
2015/04/23 13:47:05: PPPOE[01] Cannot send packet (LAN2 link down)
2015/04/23 13:47:40: same message repeated 5 times
2015/04/23 13:47:40: PP[01] Detect down via LCP keepalive
2015/04/23 13:47:40: PP[01] SEND LCP TermReq in OPENED
2015/04/23 13:47:40:   c0 21 05 02 00 04
2015/04/23 13:47:40: PP[01] STATUS LED is ON
2015/04/23 13:47:40: PPPOE[01] Cannot send packet (LAN2 link down)
2015/04/23 13:47:43: PP[01] SEND LCP TermReq in CLOSING
2015/04/23 13:47:43:   c0 21 05 03 00 04
2015/04/23 13:47:43: PPPOE[01] Cannot send packet (LAN2 link down)
2015/04/23 13:47:44:      45 00 00 40 00 5a 00 00  ff 11 4c 97 c0 a8 00 01
2015/04/23 13:47:44:      ac 10 02 02 29 a3 00 35  00 2c f6 6e 00 21 01 00
2015/04/23 13:47:44:      00 01 00 00 00 00 00 00  04 70 69 6e 67 0a 6e 65
2015/04/23 13:47:44:      74 76 6f 6c 61 6e 74 65  02 6a 70 00 00 01 00 01
2015/04/23 13:47:45: PP[01]: switched to backup
2015/04/23 13:47:46: PPPOE[01] Disconnecting, cause [PPP: LCP Keepalive failure]
2015/04/23 13:47:46: PPPOE[01] SEND PADT
2015/04/23 13:47:46:     11 a7 00 05 00 00
2015/04/23 13:47:46: PPPOE[01] Cannot send packet (LAN2 link down)
2015/04/23 13:47:47:      45 00 00 40 00 5c 00 00  ff 11 4c 95 c0 a8 00 01
2015/04/23 13:47:47:      ac 10 02 02 27 f1 00 35  00 2c f8 24 00 1d 01 00
2015/04/23 13:47:47:      00 01 00 00 00 00 00 00  04 70 69 6e 67 0a 6e 65
2015/04/23 13:47:47:      74 76 6f 6c 61 6e 74 65  02 6a 70 00 00 01 00 01
2015/04/23 13:47:47: PP[02] Calling 21 with 1B mode
2015/04/23 13:47:47: BRI[1] SEND [SETUP]
2015/04/23 13:47:47:     08 01 05 05 04 02 88 90  18 01 83 6c 02 00 80 70
2015/04/23 13:47:47:     03 80 32 31 7c 02 88 90
2015/04/23 13:47:47: BRI[1] RECV [CALL PROC]
2015/04/23 13:47:47:     08 01 85 02 18 01 89
2015/04/23 13:47:47: BRI[1] RECV [CONN]
2015/04/23 13:47:47:     08 01 85 07
2015/04/23 13:47:47: PP[02] HDLC Opening, ch = A
2015/04/23 13:47:47: PP[02] ISDN connect, going PPP phase
2015/04/23 13:47:47: PP[02] SEND LCP ConfReq in STARTING
2015/04/23 13:47:47:   ff 03 c0 21 01 01 00 0e  01 04 07 00 05 06 e4 1a
2015/04/23 13:47:47:   5f 2d
2015/04/23 13:47:47: PP[02] RECV LCP ConfReq in REQSENT
2015/04/23 13:47:47:   ff 03 c0 21 01 01 00 13  01 04 07 00 03 05 c2 23
2015/04/23 13:47:47:   05 05 06 37 f4 46 eb
2015/04/23 13:47:47: PP[02] SEND LCP ConfAck in REQSENT
2015/04/23 13:47:47:   ff 03 c0 21 02 01 00 13  01 04 07 00 03 05 c2 23
2015/04/23 13:47:47:   05 05 06 37 f4 46 eb
2015/04/23 13:47:47: PP[02] RECV LCP ConfAck in ACKSENT
2015/04/23 13:47:47:   ff 03 c0 21 02 01 00 0e  01 04 07 00 05 06 e4 1a
2015/04/23 13:47:47:   5f 2d
2015/04/23 13:47:47: PP[02] RECV CHAP Challenge in CS_LISTEN/SS_CLOSED
2015/04/23 13:47:47:   ff 03 c2 23 01 01 00 20  10 d0 d4 2f c6 e5 3a 62
2015/04/23 13:47:47:   95 50 c6 c3 90 54 fd 97  7d 31 37 32 2e 31 36 2e
2015/04/23 13:47:47:   31 32 2e 31
2015/04/23 13:47:47: PP[02] SEND CHAP Response in CS_LISTEN/SS_CLOSED
2015/04/23 13:47:47:   ff 03 c2 23 02 01 00 1a  10 61 f5 85 6c af b1 04
2015/04/23 13:47:47:   b7 61 2f 0c 6b f9 a4 93  45 75 73 65 72 32
2015/04/23 13:47:47: PP[02] RECV CHAP Success in CS_OPEN/SS_CLOSED
2015/04/23 13:47:47:   ff 03 c2 23 03 01 00 1d  41 75 74 68 65 6e 74 69
2015/04/23 13:47:47:   63 61 74 69 6f 6e 20 73  75 63 63 65 65 64 65 64
2015/04/23 13:47:47:   2e
2015/04/23 13:47:47: PP[02] SEND CCP ConfReq in STARTING
2015/04/23 13:47:47:   ff 03 80 fd 01 01 00 09  11 05 00 01 03
2015/04/23 13:47:47: PP[02] SEND IPCP ConfReq in STARTING
2015/04/23 13:47:47:   ff 03 80 21 01 01 00 16  03 06 00 00 00 00 81 06
2015/04/23 13:47:47:   00 00 00 00 83 06 00 00  00 00
2015/04/23 13:47:47: PP[02] SEND IPV6CP ConfReq in STARTING
2015/04/23 13:47:47:   ff 03 80 57 01 01 00 0e  01 0a 02 a0 de ff fe ae
2015/04/23 13:47:47:   67 89
2015/04/23 13:47:47: PP[02] RECV CCP ConfReq in REQSENT
2015/04/23 13:47:47:   ff 03 80 fd 01 01 00 09  11 05 00 01 03
2015/04/23 13:47:47: PP[02] SEND CCP ConfAck in REQSENT
2015/04/23 13:47:47:   ff 03 80 fd 02 01 00 09  11 05 00 01 03
2015/04/23 13:47:47: PP[02] RECV IPCP ConfReq in REQSENT
2015/04/23 13:47:47:   ff 03 80 21 01 01 00 04
2015/04/23 13:47:47: PP[02] SEND IPCP ConfNak in REQSENT
2015/04/23 13:47:47:   ff 03 80 21 03 01 00 0a  03 06 00 00 00 00
2015/04/23 13:47:47: PP[02] RECV IPV6CP ConfReq in REQSENT
2015/04/23 13:47:47:   ff 03 80 57 01 01 00 0e  01 0a 02 a0 de ff fe 7e
2015/04/23 13:47:47:   1d 4a
2015/04/23 13:47:47: PP[02] SEND IPV6CP ConfAck in REQSENT
2015/04/23 13:47:47:   ff 03 80 57 02 01 00 0e  01 0a 02 a0 de ff fe 7e
2015/04/23 13:47:47:   1d 4a
2015/04/23 13:47:47: PP[02] RECV CCP ConfAck in ACKSENT
2015/04/23 13:47:47:   ff 03 80 fd 02 01 00 09  11 05 00 01 03
2015/04/23 13:47:47: PP[02] RECV IPCP ConfNak in REQSENT
2015/04/23 13:47:47:   ff 03 80 21 03 01 00 16  03 06 ac 10 02 01 81 06
2015/04/23 13:47:47:   ac 10 0c 01 83 06 ac 10  0c 02
2015/04/23 13:47:47: PP[02] SEND IPCP ConfReq in REQSENT
2015/04/23 13:47:47:   ff 03 80 21 01 02 00 16  03 06 ac 10 02 01 81 06
2015/04/23 13:47:47:   ac 10 0c 01 83 06 ac 10  0c 02
2015/04/23 13:47:47: PP[02] RECV IPV6CP ConfAck in ACKSENT
2015/04/23 13:47:47:   ff 03 80 57 02 01 00 0e  01 0a 02 a0 de ff fe ae
2015/04/23 13:47:47:   67 89
2015/04/23 13:47:47: PP[02] PPP/IPV6CP up
2015/04/23 13:47:47: PP[02] Local  PP Interface-ID 02a0defffeae6789
2015/04/23 13:47:47: PP[02] Remote PP Interface-ID 02a0defffe7e1d4a
2015/04/23 13:47:47: PP[02] RECV IPCP ConfReq in REQSENT
2015/04/23 13:47:47:   ff 03 80 21 01 02 00 0a  03 06 ac 10 02 02
2015/04/23 13:47:47: PP[02] SEND IPCP ConfAck in REQSENT
2015/04/23 13:47:47:   ff 03 80 21 02 02 00 0a  03 06 ac 10 02 02
2015/04/23 13:47:47: PP[02] RECV IPCP ConfAck in ACKSENT
2015/04/23 13:47:47:   ff 03 80 21 02 02 00 16  03 06 ac 10 02 01 81 06
2015/04/23 13:47:47:   ac 10 0c 01 83 06 ac 10  0c 02
2015/04/23 13:47:47: PP[02] PPP/IPCP up  (Local: 172.16.2.1, Remote: 172.16.2.2)
2015/04/23 13:47:47: PP[02] Local  PP IP address 172.16.2.1
2015/04/23 13:47:47: PP[02] Remote PP IP address 172.16.2.2
2015/04/23 13:47:47: PPPOE[01] Disconnected, cause [PPP: LCP Keepalive failure]
2015/04/23 13:47:47: PPPOE[01] Connecting to PPPoE server
2015/04/23 13:47:47: PPPOE[01] SEND PADI
2015/04/23 13:47:47:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
2015/04/23 13:47:47:     93 ef 2f cb
2015/04/23 13:47:47: PPPOE[01] Cannot send packet (LAN2 link down)
2015/04/23 13:47:50: PPPOE[01] SEND PADI
2015/04/23 13:47:50:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
2015/04/23 13:47:50:     93 ef 2f cb
2015/04/23 13:47:50: PPPOE[01] Cannot send packet (LAN2 link down)
2015/04/23 13:47:56: PPPOE[01] SEND PADI
2015/04/23 13:47:56:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
2015/04/23 13:47:56:     93 ef 2f cb
[解説]
PP[01] Detect down via LCP keepalive:メイン回線の切断を検出しました。
PP[01]: switched to backup:バックアップに切り替わりました。
PP[02] PPP/IPCP up:バックアップ回線が接続されました。

トラブル原因と対処方法

この項目では各トラブル発生時の状態と、原因及びその解決方法を紹介します。
正常時の状態と比較することで、問題解決の助けとなります。

2-1 バックアップコマンド未設定による、バックアップ失敗

このケースは、バックアップコマンド未設定による、バックアップ失敗事例です。
以下ではルーターの状態を確認し原因の究明を行います。

ログの確認
※ログ採取の前にルーターに"syslog debug on"を設定しておきます。
(1)
   # show log
   2015/04/23 13:58:10: LAN2: link down
   2015/04/23 13:58:32: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:03: same message repeated 1 times
   2015/04/23 13:59:03: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:07: same message repeated 3 times
   2015/04/23 13:59:07: PP[01] Detect down via LCP keepalive
   2015/04/23 13:59:07: PP[01] SEND LCP TermReq in OPENED
   2015/04/23 13:59:07:   c0 21 05 02 00 04
   2015/04/23 13:59:07: PP[01] STATUS LED is ON
   2015/04/23 13:59:07: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:10: PP[01] SEND LCP TermReq in CLOSING
   2015/04/23 13:59:10:   c0 21 05 03 00 04
   2015/04/23 13:59:10: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:13: PPPOE[01] Disconnecting, cause [PPP: LCP Keepalive failure]
   2015/04/23 13:59:13: PPPOE[01] SEND PADT
   2015/04/23 13:59:13:     11 a7 00 06 00 00
   2015/04/23 13:59:13: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:14: PPPOE[01] Disconnected, cause [PPP: LCP Keepalive failure]
   2015/04/23 13:59:14: PPPOE[01] Connecting to PPPoE server
   2015/04/23 13:59:14: PPPOE[01] SEND PADI
   2015/04/23 13:59:14:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 13:59:14:     93 ef 2f cb
   2015/04/23 13:59:14: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:17: PPPOE[01] SEND PADI
   2015/04/23 13:59:17:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 13:59:17:     93 ef 2f cb
   2015/04/23 13:59:17: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:23: PPPOE[01] SEND PADI
   2015/04/23 13:59:23:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 13:59:23:     93 ef 2f cb
   2015/04/23 13:59:23: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:35: PPPOE[01] SEND PADI
   2015/04/23 13:59:35:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 13:59:35:     93 ef 2f cb
   2015/04/23 13:59:35: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:47: PPPOE[01] SEND PADI

   2015/04/23 13:59:47:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 13:59:47:     93 ef 2f cb
   2015/04/23 13:59:47: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 13:59:59: PPPOE[01] SEND PADI
   2015/04/23 13:59:59:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 13:59:59:     93 ef 2f cb
   2015/04/23 13:59:59: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 14:00:11: PPPOE[01] Disconnected, cause [PPPoE: PADI Timeout]

(2)
   # show status backup
   there's no backup configuration.
[解説]
(1)ログに"switched to backup"の表示がなく、バックアップへの切り替わりを確認できません。
 
(2)バックアップの状態が表示されていません。
 
例えば、このような原因としては以下が考えられます。
バックアップコマンド"pp backup pp 2"が設定されていない。
参考:正常時のNATディスクリプタの状態正常時のログ
対処方法
以下のバックアップコマンドを設定してください。

pp select 1
pp backup pp 2

2-2 NATディスクリプタの設定不足による、バックアップ失敗

このケースは、NATディスクリプタの設定不足による、バックアップ失敗事例です。
以下ではルーターの状態を確認し原因の究明を行います。

ケース1
# show nat descriptor address
NAT/IPマスカレード 動作タイプ : 2
参照NATディスクリプタ : 1, 適用インタフェース : PP[01](1)
Masqueradeテーブル
    外側アドレス: ipcp
    ポート範囲: 60000-64095, 49152-59999, 44096-49151
---------------------
有効なNATディスクリプタテーブルが1個ありました
[解説]
バックアップ回線側のPP[02]にNATディスクリプタが適用されていません。
参考:正常時のNATディスクリプタの状態
対処方法
PP2に対してNATディスクリプタを適用してください。
pp select 2
ip pp nat descriptor 1

2-3 バックアップ回線に切り替わっているが、通信ができない。

このケースは、バックアップ回線側IPアドレスの取得失敗によるバックアップ接続失敗事例です。
以下ではルーターの状態を確認し原因の究明を行います。
※ログ採集の前にルーターに"syslog debug on"を設定しておきます。

(1)
   # show log
   2015/04/23 14:25:42: PP[01]: switched to backup
   2015/04/23 14:25:43: PPPOE[01] Disconnecting, cause [PPP: LCP Keepalive failure]
   2015/04/23 14:25:43: PPPOE[01] SEND PADT
   2015/04/23 14:25:43:     11 a7 00 0a 00 00
   2015/04/23 14:25:43: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 14:25:44: PPPOE[01] Disconnected, cause [PPP: LCP Keepalive failure]
   2015/04/23 14:25:44: PPPOE[01] Connecting to PPPoE server
   2015/04/23 14:25:44: PPPOE[01] SEND PADI
   2015/04/23 14:25:44:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 14:25:44:     93 ef 2f cb
   2015/04/23 14:25:44: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 14:25:47: PPPOE[01] SEND PADI
   2015/04/23 14:25:47:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 14:25:47:     93 ef 2f cb
   2015/04/23 14:25:47: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 14:25:53: PPPOE[01] SEND PADI
   2015/04/23 14:25:53:     11 09 00 00 00 0e 01 01  00 00 01 03 00 06 59 e1
   2015/04/23 14:25:53:     93 ef 2f cb
   2015/04/23 14:25:53: PPPOE[01] Cannot send packet (LAN2 link down)
   2015/04/23 14:26:02:      45 00 00 5c 01 8a 00 00  ff 01 ef 6c c0 a8 00 01
   2015/04/23 14:26:02:      0a 00 00 01 08 00 d6 cd  21 70 00 00 42 69 dc 6a
   2015/04/23 14:26:02:      00 0c 08 ee 08 09 0a 0b  0c 0d 0e 0f 10 11 12 13
   2015/04/23 14:26:02:      14 15 16 17 18 19 1a 1b  1c 1d 1e 1f 20 21 22 23
   2015/04/23 14:26:02: PP[02] Calling 21 with 1B mode
   2015/04/23 14:26:02: BRI[1] SEND [SETUP]
   2015/04/23 14:26:02:     08 01 0d 05 04 02 88 90  18 01 83 6c 02 00 80 70
   2015/04/23 14:26:02:     03 80 32 31 7c 02 88 90
   2015/04/23 14:26:02: BRI[1] RECV [CALL PROC]
   2015/04/23 14:26:02:     08 01 8d 02 18 01 89
   2015/04/23 14:26:02: BRI[1] RECV [CONN]
   2015/04/23 14:26:02:     08 01 8d 07
   2015/04/23 14:26:02: PP[02] HDLC Opening, ch = A
   2015/04/23 14:26:03: PP[02] ISDN connect, going PPP phase
   2015/04/23 14:26:03: PP[02] SEND LCP ConfReq in STARTING
   2015/04/23 14:26:03:   ff 03 c0 21 01 01 00 0e  01 04 07 00 05 06 9a 16
   2015/04/23 14:26:03:   16 99
   2015/04/23 14:26:03: PP[02] RECV LCP ConfReq in REQSENT
   2015/04/23 14:26:03:   ff 03 c0 21 01 01 00 13  01 04 07 00 03 05 c2 23
   2015/04/23 14:26:03:   05 05 06 43 f4 48 23
   2015/04/23 14:26:03: PP[02] SEND LCP ConfAck in REQSENT
   2015/04/23 14:26:03:   ff 03 c0 21 02 01 00 13  01 04 07 00 03 05 c2 23
   2015/04/23 14:26:03:   05 05 06 43 f4 48 23
   2015/04/23 14:26:03: PP[02] RECV LCP ConfAck in ACKSENT
   2015/04/23 14:26:03:   ff 03 c0 21 02 01 00 0e  01 04 07 00 05 06 9a 16
   2015/04/23 14:26:03:   16 99
   2015/04/23 14:26:03: PP[02] RECV CHAP Challenge in CS_LISTEN/SS_CLOSED
   2015/04/23 14:26:03:   ff 03 c2 23 01 01 00 20  10 16 88 bf 05 a8 74 0d
   2015/04/23 14:26:03:   aa 41 36 4a 7d 04 eb 53  ed 31 37 32 2e 31 36 2e
   2015/04/23 14:26:03:   31 32 2e 31
   2015/04/23 14:26:03: PP[02] SEND CHAP Response in CS_LISTEN/SS_CLOSED
   2015/04/23 14:26:03:   ff 03 c2 23 02 01 00 1a  10 bb fa 10 81 eb c5 29
   2015/04/23 14:26:03:   7c 97 4c 81 59 05 97 02  6a 75 73 65 72 32
   2015/04/23 14:26:03: PP[02] RECV CHAP Success in CS_OPEN/SS_CLOSED
   2015/04/23 14:26:03:   ff 03 c2 23 03 01 00 1d  41 75 74 68 65 6e 74 69
   2015/04/23 14:26:03:   63 61 74 69 6f 6e 20 73  75 63 63 65 65 64 65 64
   2015/04/23 14:26:03:   2e
   2015/04/23 14:26:03: PP[02] SEND CCP ConfReq in STARTING
   2015/04/23 14:26:03:   ff 03 80 fd 01 01 00 09  11 05 00 01 03
   2015/04/23 14:26:03: PP[02] SEND IPCP ConfReq in STARTING
   2015/04/23 14:26:03:   ff 03 80 21 01 01 00 10  81 06 00 00 00 00 83 06
   2015/04/23 14:26:03:   00 00 00 00
   2015/04/23 14:26:03: PP[02] SEND IPV6CP ConfReq in STARTING
   2015/04/23 14:26:03:   ff 03 80 57 01 01 00 0e  01 0a 02 a0 de ff fe ae
   2015/04/23 14:26:03:   67 89
   2015/04/23 14:26:03: PP[02] RECV CCP ConfReq in REQSENT
   2015/04/23 14:26:03:   ff 03 80 fd 01 01 00 09  11 05 00 01 03
   2015/04/23 14:26:03: PP[02] SEND CCP ConfAck in REQSENT
   2015/04/23 14:26:03:   ff 03 80 fd 02 01 00 09  11 05 00 01 03
   2015/04/23 14:26:03: PP[02] RECV IPCP ConfReq in REQSENT
   2015/04/23 14:26:03:   ff 03 80 21 01 01 00 04
   2015/04/23 14:26:03: PP[02] SEND IPCP ConfAck in REQSENT
   2015/04/23 14:26:03:   ff 03 80 21 02 01 00 04
   2015/04/23 14:26:03: PP[02] RECV IPV6CP ConfReq in REQSENT
   2015/04/23 14:26:03:   ff 03 80 57 01 01 00 0e  01 0a 02 a0 de ff fe 7e
   2015/04/23 14:26:03:   1d 4a
   2015/04/23 14:26:03: PP[02] SEND IPV6CP ConfAck in REQSENT
   2015/04/23 14:26:03:   ff 03 80 57 02 01 00 0e  01 0a 02 a0 de ff fe 7e
   2015/04/23 14:26:03:   1d 4a
   2015/04/23 14:26:03: PP[02] RECV CCP ConfAck in ACKSENT
   2015/04/23 14:26:03:   ff 03 80 fd 02 01 00 09  11 05 00 01 03
   2015/04/23 14:26:03: PP[02] RECV IPCP ConfNak in ACKSENT
   2015/04/23 14:26:03:   ff 03 80 21 03 01 00 10  81 06 ac 10 0c 01 83 06
   2015/04/23 14:26:03:   ac 10 0c 02
   2015/04/23 14:26:03: PP[02] SEND IPCP ConfReq in ACKSENT
   2015/04/23 14:26:03:   ff 03 80 21 01 02 00 10  81 06 ac 10 0c 01 83 06
   2015/04/23 14:26:03:   ac 10 0c 02
   2015/04/23 14:26:03: PP[02] RECV IPV6CP ConfAck in ACKSENT
   2015/04/23 14:26:03:   ff 03 80 57 02 01 00 0e  01 0a 02 a0 de ff fe ae
   2015/04/23 14:26:03:   67 89
   2015/04/23 14:26:03: PP[02] PPP/IPV6CP up
   2015/04/23 14:26:03: PP[02] Local  PP Interface-ID 02a0defffeae6789
   2015/04/23 14:26:03: PP[02] Remote PP Interface-ID 02a0defffe7e1d4a
   2015/04/23 14:26:03: PP[02] RECV IPCP ConfAck in ACKSENT
   2015/04/23 14:26:03:   ff 03 80 21 02 02 00 10  81 06 ac 10 0c 01 83 06
   2015/04/23 14:26:03:   ac 10 0c 02
   2015/04/23 14:26:03: PP[02] PPP/IPCP up  (Local: None, Remote: None)
   2015/04/23 14:26:03: PP[02] Local  PP IP address 0.0.0.0
   2015/04/23 14:26:03: PP[02] Remote PP IP address 0.0.0.0

(2)
   # show nat descriptor address
   参照NATディスクリプタ : 1, 適用インタフェース : PP[01](1)
   Masqueradeテーブル
       外側アドレス: ipcp
       ポート範囲: 60000-64095, 49152-59999, 44096-49151
   ---------------------
   参照NATディスクリプタ : 1, 適用インタフェース : PP[02](1)
   Masqueradeテーブル
       外側アドレス: ipcp
       ポート範囲: 60000-64095, 49152-59999, 44096-49151   1個使用中
         No.       内側アドレス      使用中のポート数    制限数         種別
          1         192.168.0.1                     1     20000         dynamic
   ---------------------
   有効なNATディスクリプタテーブルが2個ありました
[解説]
(1)ログには"PP[02] PPP/IPCP up"が表示され、バックアップ回線が接続していることを確認できますが、"PP[02] Local PP IP address 0.0.0.0"の表示からWAN側のIPアドレスを取得していないことが分かります。
 
(2)"show nat descriptor address"コマンドでNATディスクリプタの状態を確認すると、PP[02]では外側アドレスが表示されていません。
 
参考:正常時のNATディスクリプタの状態正常時のログ
対処方法
以下のように、プロバイダからIPアドレスを取得する設定を行ってください。
pp select 2
ppp ipcp ipaddress on

それでも問題が解決しない場合は、サポート窓口までご相談ください。