Candy-piの接続PPP cannot be online

お世話になっております。

Raspberry Pi の情報

Raspberry Pi reference 2019-09-26
Generated using pi-gen, GitHub - RPi-Distro/pi-gen: Tool used to create the raspberrypi.org Raspbian images, 80d486687ea77d31fc3fc13cf3a2f8b464e129be, stage5

他のGPIOは不使用状態です。

candy-piはコマンドでインストールしました。
curl -sL https://git.io/v7bXx | sudo bash
candy-pi-lite.service - CANDY Pi Lite Service, version:10.4.0

APNの設定は
sudo vi /boot/apn
{
“apn”: “mopera.net”,
“user”: “”,
“password”: “”,
“cs”: false
}
の環境で動かしています

起動してから、二日間で接続がまだできない状態です。
失敗した要因と対策が分かれば教えてください。

下記のsyslogをご覧ください。

Sep 28 13:30:24 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 115200…
Sep 28 13:30:25 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:30:25 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 460800…
Sep 28 13:30:27 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:30:28 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 115200…
Sep 28 13:30:29 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:30:29 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 460800…
Sep 28 13:30:30 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:30:31 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 115200…
Sep 28 13:30:31 raspberrypi candy-pi-lite: [INFO] Result: /dev/ttySC1
Sep 28 13:30:31 raspberrypi candy-pi-lite: [INFO] Modem Serial port: /dev/ttySC1 and AT Serial port: /dev/ttySC1 are sel$
Sep 28 13:30:31 raspberrypi candy-pi-lite: [INFO] Initializing modem with baudrate:460800
Sep 28 13:30:32 raspberrypi candy-pi-lite: [INFO] Modem baudrate changed: 115200 => 460800
Sep 28 13:30:32 raspberrypi candy-pi-lite: [INFO] Initialization Done. Modem Serial Port => /dev/ttySC1 Modem baudrate =$
Sep 28 13:30:33 raspberrypi candy-pi-lite: [INFO] SIM card state => SIM_STATE_READY
Sep 28 13:30:34 raspberrypi dbus-daemon[459]: [system] Activating via systemd: service name=‘org.freedesktop.timedate1’ $
Sep 28 13:30:34 raspberrypi systemd[1]: Starting Time & Date Service…
Sep 28 13:30:34 raspberrypi dbus-daemon[459]: [system] Successfully activated service ‘org.freedesktop.timedate1’
Sep 28 13:30:34 raspberrypi systemd[1]: Started Time & Date Service.
Sep 28 13:30:34 raspberrypi systemd-timedated[18216]: Set NTP to disabled
Sep 28 13:30:35 raspberrypi candy-pi-lite: [INFO] EC25 Phone Functionality => Full
Sep 28 13:30:35 raspberrypi candy-pi-lite: [INFO] Saved APN => mopera.net
Sep 28 13:30:36 raspberrypi candy-pi-lite: [INFO] Operator => JP DOCOMO NTT DOCOMO
Sep 28 13:30:36 raspberrypi candy-pi-lite: [INFO] Waiting for network registration => Status:Searching
Sep 28 13:30:38 raspberrypi candy-pi-lite: [INFO] Operator => NTT DOCOMO NTT DOCOMO
Sep 28 13:30:39 raspberrypi candy-pi-lite: [INFO] OK. Registered in the home ps network
Sep 28 13:30:40 raspberrypi candy-pi-lite: [INFO] Module Model: EC25
Sep 28 13:30:40 raspberrypi candy-pi-lite: [INFO] Network Timezone: 9.0
Sep 28 13:30:40 raspberrypi candy-pi-lite: [INFO] Skipped to adjust time
Sep 28 13:30:40 raspberrypi candy-pi-lite: [INFO] Trying to establish a connection…
Sep 28 13:30:40 raspberrypi start_systemd.sh[18097]: RTNETLINK answers: No such process
Sep 28 13:30:40 raspberrypi candy-pi-lite: [INFO] Trying to connect…(Trial:1/3, Timeout:5sec)
Sep 28 13:30:40 raspberrypi candy-pi-lite: [INFO] Starting PPP: /dev/ttySC1
Sep 28 13:30:40 raspberrypi candy-pi-lite: [INFO] start_pppd.sh terminated: Exit Code => 127
Sep 28 13:30:41 raspberrypi candy-pi-lite: [ERROR] PPP cannot be online
Sep 28 13:30:41 raspberrypi candy-pi-lite: [INFO] Trying to connect…(Trial:2/3, Timeout:35sec)
Sep 28 13:30:41 raspberrypi candy-pi-lite: [INFO] Starting PPP: /dev/ttySC1
Sep 28 13:30:41 raspberrypi candy-pi-lite: [INFO] start_pppd.sh terminated: Exit Code => 127
Sep 28 13:30:42 raspberrypi candy-pi-lite: [ERROR] PPP cannot be online
Sep 28 13:30:42 raspberrypi candy-pi-lite: [INFO] Trying to connect…(Trial:3/3, Timeout:65sec)
Sep 28 13:30:42 raspberrypi candy-pi-lite: [INFO] Starting PPP: /dev/ttySC1
Sep 28 13:30:42 raspberrypi candy-pi-lite: [INFO] start_pppd.sh terminated: Exit Code => 127
Sep 28 13:30:43 raspberrypi candy-pi-lite: [ERROR] PPP cannot be online
Sep 28 13:30:43 raspberrypi candy-pi-lite: [WARN] Failed to establishing a connection. Retry after 30 seconds…
Sep 28 13:31:04 raspberrypi systemd[1]: systemd-timedated.service: Succeeded.
Sep 28 13:31:16 raspberrypi candy-pi-lite: [ERROR] Modem is missing
Sep 28 13:31:18 raspberrypi candy-pi-lite: [ERROR] Modem is missing
Sep 28 13:31:18 raspberrypi candy-pi-lite: [ERROR] No serialport is available, will restart after 10 seconds
Sep 28 13:31:28 raspberrypi systemd[1]: candy-pi-lite.service: Main process exited, code=exited, status=5/NOTINSTALLED
Sep 28 13:31:28 raspberrypi systemd[1]: candy-pi-lite.service: Failed with result ‘exit-code’.
Sep 28 13:31:28 raspberrypi systemd[1]: candy-pi-lite.service: Service RestartSec=100ms expired, scheduling restart.
Sep 28 13:31:28 raspberrypi systemd[1]: candy-pi-lite.service: Scheduled restart job, restart counter is at 4.
Sep 28 13:31:28 raspberrypi systemd[1]: Stopped CANDY Pi Lite Service, version:10.4.0.
Sep 28 13:31:28 raspberrypi systemd[1]: Started CANDY Pi Lite Service, version:10.4.0.
Sep 28 13:31:28 raspberrypi candy-pi-lite: [INFO] Initializing CANDY Pi Lite Board…
Sep 28 13:31:29 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 115200…
Sep 28 13:31:30 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:31:30 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 460800…
Sep 28 13:31:31 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:31:31 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 115200…
Sep 28 13:31:32 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:31:32 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 460800…
Sep 28 13:31:33 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:31:35 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 115200…
Sep 28 13:31:36 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:31:36 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 460800…
Sep 28 13:31:37 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:31:38 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 115200…
Sep 28 13:31:39 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:31:39 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 460800…
Sep 28 13:31:40 raspberrypi candy-pi-lite: [INFO] Result: None
Sep 28 13:31:41 raspberrypi candy-pi-lite: [INFO] Looking for a Modem Serial port at Barudrate: 115200…
Sep 28 13:31:41 raspberrypi candy-pi-lite: [INFO] Result: /dev/ttySC1
Sep 28 13:31:41 raspberrypi candy-pi-lite: [INFO] Modem Serial port: /dev/ttySC1 and AT Serial port: /dev/ttySC1 are sel$
Sep 28 13:31:42 raspberrypi candy-pi-lite: [INFO] Initializing modem with baudrate:460800
Sep 28 13:31:43 raspberrypi candy-pi-lite: [INFO] Modem baudrate changed: 115200 => 460800
Sep 28 13:31:43 raspberrypi candy-pi-lite: [INFO] Initialization Done. Modem Serial Port => /dev/ttySC1 Modem baudrate =$
Sep 28 13:31:44 raspberrypi candy-pi-lite: [INFO] SIM card state => SIM_STATE_READY
Sep 28 13:31:44 raspberrypi dbus-daemon[459]: [system] Activating via systemd: service name=‘org.freedesktop.timedate1’ $
Sep 28 13:31:44 raspberrypi systemd[1]: Starting Time & Date Service…
Sep 28 13:31:44 raspberrypi dbus-daemon[459]: [system] Successfully activated service ‘org.freedesktop.timedate1’
Sep 28 13:31:44 raspberrypi systemd[1]: Started Time & Date Service.
Sep 28 13:31:44 raspberrypi systemd-timedated[18575]: Set NTP to disabled
Sep 28 13:31:45 raspberrypi candy-pi-lite: [INFO] EC25 Phone Functionality => Full
Sep 28 13:31:45 raspberrypi candy-pi-lite: [INFO] Saved APN => mopera.net
Sep 28 13:31:46 raspberrypi candy-pi-lite: [INFO] Operator => N/A
Sep 28 13:31:46 raspberrypi candy-pi-lite: [INFO] Waiting for network registration => Status:Searching
Sep 28 13:31:49 raspberrypi candy-pi-lite: [INFO] Operator => JP DOCOMO NTT DOCOMO
Sep 28 13:31:49 raspberrypi candy-pi-lite: [INFO] Waiting for network registration => Status:Searching
Sep 28 13:31:51 raspberrypi candy-pi-lite: [INFO] Operator => NTT DOCOMO NTT DOCOMO
Sep 28 13:31:51 raspberrypi candy-pi-lite: [INFO] OK. Registered in the home ps network
Sep 28 13:31:52 raspberrypi candy-pi-lite: [INFO] Module Model: EC25
Sep 28 13:31:52 raspberrypi candy-pi-lite: [INFO] Network Timezone: 9.0
Sep 28 13:31:52 raspberrypi candy-pi-lite: [INFO] Skipped to adjust time
Sep 28 13:31:52 raspberrypi candy-pi-lite: [INFO] Trying to establish a connection…
Sep 28 13:31:52 raspberrypi start_systemd.sh[18456]: RTNETLINK answers: No such process
Sep 28 13:31:52 raspberrypi candy-pi-lite: [INFO] Trying to connect…(Trial:1/3, Timeout:5sec)
Sep 28 13:31:52 raspberrypi candy-pi-lite: [INFO] Starting PPP: /dev/ttySC1
Sep 28 13:31:52 raspberrypi candy-pi-lite: [INFO] start_pppd.sh terminated: Exit Code => 127
Sep 28 13:31:53 raspberrypi candy-pi-lite: [ERROR] PPP cannot be online
Sep 28 13:31:53 raspberrypi candy-pi-lite: [INFO] Trying to connect…(Trial:2/3, Timeout:35sec)
Sep 28 13:31:53 raspberrypi candy-pi-lite: [INFO] Starting PPP: /dev/ttySC1
Sep 28 13:31:53 raspberrypi candy-pi-lite: [INFO] start_pppd.sh terminated: Exit Code => 127
Sep 28 13:31:54 raspberrypi candy-pi-lite: [ERROR] PPP cannot be online
Sep 28 13:31:54 raspberrypi candy-pi-lite: [INFO] Trying to connect…(Trial:3/3, Timeout:65sec)
Sep 28 13:31:54 raspberrypi candy-pi-lite: [INFO] Starting PPP: /dev/ttySC1
Sep 28 13:31:54 raspberrypi candy-pi-lite: [INFO] start_pppd.sh terminated: Exit Code => 127
Sep 28 13:31:55 raspberrypi candy-pi-lite: [ERROR] PPP cannot be online
Sep 28 13:31:55 raspberrypi candy-pi-lite: [WARN] Failed to establishing a connection. Retry after 30 seconds…

ご質問ありがとうございます。

syslogからは、SIMが装着されており、電波も問題ないことはログからは判別できます。
ですが、PPP接続において以下のようなエラーとなっております。

通常はこれらのログの間にPPP側のメッセージが以下のような形で出るのですがそれが出ずにすぐにエラーとなっているように見えます。

##注意:このメッセージはうまく接続した時の例です##
Jan 12 18:05:35 raspberrypi pppd[950]: pppd 2.4.7 started by root, uid 0
Jan 12 18:05:36 raspberrypi pppd[950]: Serial connection established.
Jan 12 18:05:36 raspberrypi pppd[950]: Using interface ppp0
Jan 12 18:05:36 raspberrypi pppd[950]: Connect: ppp0 <–> /dev/ttySC1
Jan 12 18:05:37 raspberrypi pppd[950]: CHAP authentication succeeded
Jan 12 18:05:37 raspberrypi pppd[950]: CHAP authentication succeeded

この状況ですと設定の問題か環境の問題か判別できないため、恐れ入りますが試しにこちらにあるOSイメージの一覧から最新のものを使って(必要なソフトウェアが入っている状態のOSイメージです)、APN設定だけを変更して接続するかどうかをご確認いただけますでしょうか。

お手数をおかけしますがよろしくお願いいたします。

お世話になっております。

おかげさまで、接続ができました。

ありがとうございました。

1 Like