Re: No 3G after disconnecting
- From: wp1191918-hgvs <public van-schelve de>
- To: networkmanager-list gnome org, Marius Kotsbak <marius kotsbak gmail com>
- Subject: Re: No 3G after disconnecting
- Date: Tue, 26 Jul 2011 12:17:14 +0200 (CEST)
Marius Kotsbak <marius kotsbak gmail com> hat am 26. Juli 2011 um 02:17
geschrieben:
> Den 25. juli 2011 12:08, skrev wp1191918-hgvs:
> > Hi,
> >
> > I have found the following problem with an Ericsson F5521gw 3G modem:
> >
> > 1. start system
> > 2. bring up 3g connection by clicking on the configureation in nm-applet
> > 3. wait until connection is established and can be used
> > 4. disconnect from 3g connection
> > 5. when trying to bring up the connection again it fails
> >
> Seems like I found the cause. See my attached patch to the bug report:
>
> https://bugzilla.gnome.org/show_bug.cgi?id=650740
>
> --
> Marius
Hi Marius,
looks like with your patch I am no longer able to bring up the 3G connection on
any time.
I have captured the debug:
** (modem-manager:17857): DEBUG: <1311674406.968858> (ttyACM0) opening serial
port...
** (modem-manager:17857): DEBUG: <1311674406.969198> (ttyACM0) device open count
is 1 (open)
** (modem-manager:17857): DEBUG: <1311674406.969239> Modem
/org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
** (modem-manager:17857): DEBUG: <1311674407.70317> (ttyACM0): --> 'ATZ E0
V1<CR>'
** (modem-manager:17857): DEBUG: <1311674407.81300> (ttyACM0): <-- 'ATZ
E0<CR><LF>*EMRDY: 1<CR><LF> V1<CR>'
** (modem-manager:17857): DEBUG: <1311674407.86296> (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.86561> (ttyACM0): --> 'ATE0<CR>'
** (modem-manager:17857): DEBUG: <1311674407.94383> (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.94626> (ttyACM0): -->
'AT+CMEE=1<CR>'
** (modem-manager:17857): DEBUG: <1311674407.106294> (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.106517> (ttyACM0): --> 'ATX4
&C1<CR>'
** (modem-manager:17857): DEBUG: <1311674407.118375> (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.118615> (ttyACM0): -->
'AT+CFUN=1<CR>'
** (modem-manager:17857): DEBUG: <1311674407.146329> (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.146502> (ttyACM1) opening serial
port...
** (modem-manager:17857): DEBUG: <1311674407.147068> (ttyACM1) device open count
is 1 (open)
** (modem-manager:17857): DEBUG: <1311674407.147223> (ttyACM0): -->
'AT+IFC=1,1<CR>'
** (modem-manager:17857): DEBUG: <1311674407.159581> (ttyACM0): <--
'<CR><LF>ERROR<CR><LF>'
** (modem-manager:17857): DEBUG: Got failure code 100: Unknown error
** (modem-manager:17857): DEBUG: <1311674407.159755> (ttyACM1): <--
'<CR><LF>*EMRDY: 1<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.159878> (ttyACM0): -->
'AT+CSCS="UTF-8"<CR>'
** (modem-manager:17857): DEBUG: <1311674407.178268> (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.178437> (ttyACM0): -->
'AT+CSCS?<CR>'
** (modem-manager:17857): DEBUG: <1311674407.188459> (ttyACM0): <--
'<CR><LF>+CSCS: "'
** (modem-manager:17857): DEBUG: <1311674407.190277> (ttyACM0): <--
'UTF-8"<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.190535> Modem
/org/freedesktop/ModemManager/Modems/0: state changed (enabling -> registered)
** (modem-manager:17857): DEBUG: <1311674407.190854> (ttyACM0): -->
'AT+CREG=2<CR>'
** (modem-manager:17857): DEBUG: <1311674407.206247> (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.206411> (ttyACM0): --> 'AT+CSQ<CR>'
** (modem-manager:17857): DEBUG: <1311674407.218377> (ttyACM0): <--
'<CR><LF>+CSQ: 13,99<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.218877> (ttyACM0): -->
'AT+CREG?<CR>'
** (modem-manager:17857): DEBUG: <1311674407.234408> (ttyACM0): <--
'<CR><LF>+CREG: 2,1,"16CA","007FAFBB"<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: CS registration state changed: 1
** (modem-manager:17857): DEBUG: <1311674407.234802> (ttyACM0): -->
'AT+CGREG=2<CR>'
** (modem-manager:17857): DEBUG: <1311674407.250379> (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.250737> (ttyACM0): -->
'AT+COPS=3,2;+COPS?<CR>'
** (modem-manager:17857): DEBUG: <1311674407.274307> (ttyACM0): <--
'<CR><LF>+COPS: 0,2,"26201",2 <CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.274675> (ttyACM0): -->
'AT+COPS=3,0;+COPS?<CR>'
** (modem-manager:17857): DEBUG: <1311674407.306407> (ttyACM0): <--
'<CR><LF>+COPS: 0,0,"T-Mobile D",2 <CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.306900> (ttyACM0): --> 'AT+CSQ<CR>'
** (modem-manager:17857): DEBUG: <1311674407.318306> (ttyACM0): <--
'<CR><LF>+CSQ: 13,99<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674407.318512> (ttyACM0): -->
'AT+CGREG?<CR>'
** (modem-manager:17857): DEBUG: <1311674407.330332> (ttyACM0): <--
'<CR><LF>+CGREG: 2,1,"16CA","007FAFBB",2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674419.304153> (ttyACM0): network_mode =>
0
** (modem-manager:17857): DEBUG: <1311674419.304153> (ttyACM0): username =>
"xxx"
** (modem-manager:17857): DEBUG: <1311674419.304153> (ttyACM0): number => "*99#"
** (modem-manager:17857): DEBUG: <1311674419.304153> (ttyACM0): apn =>
"my-apn.tda.t-d1.de"
** (modem-manager:17857): DEBUG: <1311674419.304153> (ttyACM0): allowed_mode =>
0
** (modem-manager:17857): DEBUG: <1311674419.304153> (ttyACM0): password =>
"yyy"
** (modem-manager:17857): DEBUG: <1311674419.304424> (ttyACM0): simple connect
state 0
** (modem-manager:17857): DEBUG: <1311674419.304506> (ttyACM0): simple connect
state 2
** (modem-manager:17857): DEBUG: <1311674419.304684> (ttyACM0): -->
'AT+CREG?<CR>'
** (modem-manager:17857): DEBUG: <1311674419.318286> (ttyACM0): <--
'<CR><LF>+CREG: 2,1,"16CA","007FAFBB"<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674419.318516> (ttyACM0): simple connect
state 4
** (modem-manager:17857): DEBUG: <1311674419.318624> (ttyACM0): -->
'AT+CGDCONT?<CR>'
** (modem-manager:17857): DEBUG: <1311674419.378377> (ttyACM0): <--
'<CR><LF>+CGDCONT:
1,"IP","my-apn.tda.t-d1.de","0.0.0.0",0,0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674419.378678> (ttyACM0): simple connect
state 5
** (modem-manager:17857): DEBUG: <1311674419.378803> Modem
/org/freedesktop/ModemManager/Modems/0: state changed (registered -> connecting)
** (modem-manager:17857): DEBUG: <1311674419.378905> (ttyACM0): -->
'ATD*99***1#<CR>'
** (modem-manager:17857): DEBUG: <1311674420.394363> (ttyACM0): <--
'<CR><LF>CONNECT<CR><LF>~\-1}#\-64!}!}!} }=}#}%\-62#}%}(}"}'}"}!}$}%\-36}"}&} }
} } }%}&}/\-58\-37}0}(\-78~'
** (modem-manager:17857): DEBUG: <1311674420.394577> (ttyACM0): port now
connected
** (modem-manager:17857): DEBUG: <1311674420.394673> Modem
/org/freedesktop/ModemManager/Modems/0: state changed (connecting -> connected)
** (modem-manager:17857): DEBUG: <1311674420.394765> (ttyACM0): simple connect
state 6
** (modem-manager:17857): DEBUG: (net/ppp0): could not get port's parent device
** (modem-manager:17857): DEBUG: <1311674437.17452> (ttyACM1): --> 'AT+CSQ<CR>'
** (modem-manager:17857): DEBUG: <1311674437.25556> (ttyACM1): <-- 'AT+CSQ<CR>'
** (modem-manager:17857): DEBUG: <1311674437.30317> (ttyACM1): <--
'<CR><LF>+CSQ: 12,99<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674441.3105> Modem
/org/freedesktop/ModemManager/Modems/0: state changed (connected ->
disconnecting)
** (modem-manager:17857): DEBUG: <1311674441.3315> (ttyACM1): -->
'AT+CGACT=0,1<CR>'
** (modem-manager:17857): DEBUG: <1311674441.17705> (ttyACM1): <--
'AT+CGACT=0,1<CR>'
** (modem-manager:17857): DEBUG: <1311674441.22432> (ttyACM1): <--
'<CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674442.24762> (ttyACM0): port now
disconnected
** (modem-manager:17857): DEBUG: <1311674442.24970> Modem
/org/freedesktop/ModemManager/Modems/0: state changed (disconnecting ->
registered)
** (modem-manager:17857): DEBUG: <1311674442.34274> (ttyACM0): <--
'<CR><LF>*EMRDY: 1<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674467.25228> (ttyACM0): --> 'AT+CSQ<CR>'
** (modem-manager:17857): DEBUG: <1311674467.33102> (ttyACM0): <-- 'AT+CSQ<CR>'
** (modem-manager:17857): DEBUG: <1311674467.38255> (ttyACM0): <--
'<CR><LF>+CSQ: 12,99<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:17857): DEBUG: <1311674470.627274> (ttyACM0): network_mode =>
0
** (modem-manager:17857): DEBUG: <1311674470.627274> (ttyACM0): username =>
"xxx"
** (modem-manager:17857): DEBUG: <1311674470.627274> (ttyACM0): number => "*99#"
** (modem-manager:17857): DEBUG: <1311674470.627274> (ttyACM0): apn =>
"my-apn.tda.t-d1.de"
** (modem-manager:17857): DEBUG: <1311674470.627274> (ttyACM0): allowed_mode =>
0
** (modem-manager:17857): DEBUG: <1311674470.627274> (ttyACM0): password =>
"yyy"
** (modem-manager:17857): DEBUG: <1311674470.627376> (ttyACM0): simple connect
state 0
** (modem-manager:17857): DEBUG: <1311674470.627410> (ttyACM0): simple connect
state 2
** (modem-manager:17857): DEBUG: <1311674470.627452> (ttyACM0): -->
'AT+CREG?<CR>'
** (modem-manager:17857): DEBUG: <1311674470.637315> (ttyACM0): <--
'AT+CREG?<CR>'
** (modem-manager:17857): DEBUG: <1311674470.642257> (ttyACM0): <--
'<CR><LF>+CREG: 0,1<CR><LF><CR><LF>OK<CR><LF>'
>>>> /var/log/syslog >>>>
Jul 26 12:00:06 c457826 NetworkManager[1058]: <debug> [1311674406.964838]
[nm-manager.c:4502] manager_radio_user_toggled(): (WWAN): setting radio enabled
by user
Jul 26 12:00:06 c457826 NetworkManager[1058]: <debug> [1311674406.968390]
[nm-manager.c:1702] manager_update_radio_enabled(): (ttyACM0): setting radio
enabled
Jul 26 12:00:07 c457826 NetworkManager[1058]: <info> WWAN now enabled by
management service
Jul 26 12:00:07 c457826 NetworkManager[1058]: <debug> [1311674407.191776]
[nm-manager.c:1879] manager_rfkill_update_one_type(): WWAN hw-enabled 1
sw-enabled 1 daemon-enabled 1
Jul 26 12:00:07 c457826 NetworkManager[1058]: <debug> [1311674407.191844]
[nm-manager.c:1702] manager_update_radio_enabled(): (ttyACM0): setting radio
enabled
Jul 26 12:00:07 c457826 NetworkManager[1058]: <debug> [1311674407.272076]
[nm-manager.c:1879] manager_rfkill_update_one_type(): WWAN hw-enabled 1
sw-enabled 1 daemon-enabled 1
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> Activation (ttyACM0)
starting connection '3G-Mobil'
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 3 -> 4 (reason 0)
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) scheduled...
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) started...
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 4 -> 6 (reason 0)
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) complete.
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) scheduled...
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) started...
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 6 -> 4 (reason 0)
Jul 26 12:00:19 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) complete.
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
2 of 5 (Device Configure) scheduled...
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
2 of 5 (Device Configure) starting...
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 4 -> 5 (reason 0)
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
2 of 5 (Device Configure) successful.
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
3 of 5 (IP Configure Start) scheduled.
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
2 of 5 (Device Configure) complete.
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
3 of 5 (IP Configure Start) started...
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 5 -> 7 (reason 0)
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> starting PPP connection
Jul 26 12:00:20 c457826 NetworkManager[1058]: <debug> [1311674420.399976]
[nm-ppp-manager.c:958] nm_ppp_manager_start(): command line: /usr/sbin/pppd
nodetach lock nodefaultroute user xxx ttyACM0 noipdefault noauth refuse-eap
refuse-pap refuse-mschap refuse-mschap-v2 crtscts usepeerdns lcp-echo-failure 4
lcp-echo-interval 30 ipparam /org/freedesktop/NetworkManager/PPP/4 plugin
/usr/lib/pppd/2.4.5/nm-pppd-plugin.so
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> pppd started with pid 18436
Jul 26 12:00:20 c457826 NetworkManager[1058]: <debug> [1311674420.402453]
[NetworkManagerUtils.c:721] nm_utils_get_proc_sys_net_value(): (ttyACM0): error
reading /proc/sys/net/ipv6/conf/ttyACM0/accept_ra: (4) Failed to open file
'/proc/sys/net/ipv6/conf/ttyACM0/accept_ra': No such file or directory
Jul 26 12:00:20 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
3 of 5 (IP Configure Start) complete.
Jul 26 12:00:20 c457826 pppd[18436]: Plugin
/usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
Jul 26 12:00:20 c457826 pppd[18436]: pppd 2.4.5 started by root, uid 0
Jul 26 12:00:20 c457826 NetworkManager[1058]: SCPlugin-Ifupdown: devices
added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Jul 26 12:00:20 c457826 NetworkManager[1058]: SCPlugin-Ifupdown: device added
(path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration
found.
Jul 26 12:00:20 c457826 NetworkManager[1058]: <debug> [1311674420.419552]
[nm-udev-manager.c:497] handle_uevent(): UDEV event: action 'add' subsys 'net'
device 'ppp0'
Jul 26 12:00:20 c457826 NetworkManager[1058]: <warn>
/sys/devices/virtual/net/ppp0: couldn't determine device driver; ignoring...
Jul 26 12:00:20 c457826 NetworkManager[1058]: <debug> [1311674420.422031]
[nm-netlink-monitor.c:117] link_msg_handler(): netlink link message: iface idx
10 flags 0x1090
Jul 26 12:00:20 c457826 pppd[18436]: Using interface ppp0
Jul 26 12:00:20 c457826 pppd[18436]: Connect: ppp0 <--> /dev/ttyACM0
Jul 26 12:00:20 c457826 pppd[18436]: CHAP authentication succeeded:
Congratulations!
Jul 26 12:00:20 c457826 pppd[18436]: CHAP authentication succeeded
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.204689]
[nm-netlink-monitor.c:117] link_msg_handler(): netlink link message: iface idx 4
flags 0x1003
Jul 26 12:00:36 c457826 wpa_supplicant[1183]: WPS-AP-AVAILABLE
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208520]
[wpa.c:443] wpa_parse_wpa_ie(): WPA IE: -------------------
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208570]
[wpa.c:444] wpa_parse_wpa_ie(): proto 0x2
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208599]
[wpa.c:445] wpa_parse_wpa_ie(): pw cipher 0xC
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208625]
[wpa.c:446] wpa_parse_wpa_ie(): gr cipher 0x4
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208650]
[wpa.c:447] wpa_parse_wpa_ie(): key mgmt 0x2
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208677]
[wpa.c:448] wpa_parse_wpa_ie(): capabilities 0x0
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208703]
[wpa.c:449] wpa_parse_wpa_ie(): # pmkid 0x0
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208729]
[wpa.c:450] wpa_parse_wpa_ie():
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208789]
[wpa.c:443] wpa_parse_wpa_ie(): WPA IE: -------------------
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208819]
[wpa.c:444] wpa_parse_wpa_ie(): proto 0x4
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208845]
[wpa.c:445] wpa_parse_wpa_ie(): pw cipher 0xC
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208872]
[wpa.c:446] wpa_parse_wpa_ie(): gr cipher 0x4
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208898]
[wpa.c:447] wpa_parse_wpa_ie(): key mgmt 0x2
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208936]
[wpa.c:448] wpa_parse_wpa_ie(): capabilities 0x0
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208962]
[wpa.c:449] wpa_parse_wpa_ie(): # pmkid 0x0
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.208987]
[wpa.c:450] wpa_parse_wpa_ie():
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.209204]
[nm-device-wifi.c:1619] wireless_qual_to_percent(): QL: qual 58/58/0x3A, level
-52/204/0xCC, noise 0/0/0x0, updated: 0x7 ** MAX: qual 70/70/0x46, level
-110/146/0x92, noise 0/0/0x0, updated: 0x4B
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.209239]
[nm-device-wifi.c:1679] wireless_qual_to_percent(): QL2: level_percent is 100.
max_level 146, level 146.
Jul 26 12:00:36 c457826 NetworkManager[1058]: <debug> [1311674436.209269]
[nm-device-wifi.c:1689] wireless_qual_to_percent(): QL: Final quality percent is
82 (82).
Jul 26 12:00:38 c457826 automount[3634]: 5 remaining in /cifs
Jul 26 12:00:41 c457826 NetworkManager[1058]: <warn> pppd timed out or didn't
initialize our dbus module
Jul 26 12:00:41 c457826 pppd[18436]: Terminating on signal 15
Jul 26 12:00:41 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 7 -> 9 (reason 5)
Jul 26 12:00:41 c457826 NetworkManager[1058]: <info> Marking connection
'3G-Mobil' invalid.
Jul 26 12:00:41 c457826 NetworkManager[1058]: <warn> Activation (ttyACM0)
failed.
Jul 26 12:00:41 c457826 NetworkManager[1058]: <debug> [1311674441.3375]
[nm-device.c:3669] failed_to_disconnected(): (ttyACM0): running
failed->disconnected transition
Jul 26 12:00:41 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 9 -> 3 (reason 0)
Jul 26 12:00:41 c457826 NetworkManager[1058]: <info> (ttyACM0): deactivating
device (reason: 0).
Jul 26 12:00:41 c457826 NetworkManager[1058]: <debug> [1311674441.4145]
[nm-system.c:1349] flush_routes(): (ttyACM0) failed to lookup interface index
Jul 26 12:00:41 c457826 pppd[18436]: Connection terminated.
Jul 26 12:00:41 c457826 NetworkManager[1058]: <info> Policy set 'Auto eth0'
(eth0) as default for IPv4 routing and DNS.
Jul 26 12:00:41 c457826 NetworkManager[1058]: <info> Policy set 'Auto eth0'
(eth0) as default for IPv4 routing and DNS.
Jul 26 12:00:41 c457826 NetworkManager[1058]: <debug> [1311674441.99993]
[nm-netlink-monitor.c:117] link_msg_handler(): netlink link message: iface idx
10 flags 0x1090
Jul 26 12:00:41 c457826 NetworkManager[1058]: SCPlugin-Ifupdown: devices
removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Jul 26 12:00:41 c457826 NetworkManager[1058]: <debug> [1311674441.100602]
[nm-udev-manager.c:497] handle_uevent(): UDEV event: action 'remove' subsys
'net' device 'ppp0'
Jul 26 12:00:42 c457826 pppd[18436]: Exit.
Jul 26 12:00:43 c457826 NetworkManager[1058]: <debug> [1311674443.1161]
[nm-ppp-manager.c:1034] ensure_killed(): waiting for pppd pid 18436 to exit
Jul 26 12:00:43 c457826 NetworkManager[1058]: <debug> [1311674443.1326]
[nm-ppp-manager.c:1036] ensure_killed(): pppd pid 18436 cleaned up
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Activation (ttyACM0)
starting connection '3G-Mobil'
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 3 -> 4 (reason 0)
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) scheduled...
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) started...
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 4 -> 6 (reason 0)
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) complete.
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) scheduled...
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) started...
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 6 -> 4 (reason 0)
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Activation (ttyACM0) Stage
1 of 5 (Device Prepare) complete.
Jul 26 12:01:10 c457826 NetworkManager[1058]: <warn> GSM connection failed: (32)
Unknown registration status response
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 4 -> 9 (reason 1)
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Marking connection
'3G-Mobil' invalid.
Jul 26 12:01:10 c457826 NetworkManager[1058]: <warn> Activation (ttyACM0)
failed.
Jul 26 12:01:10 c457826 NetworkManager[1058]: <debug> [1311674470.644538]
[nm-device.c:3669] failed_to_disconnected(): (ttyACM0): running
failed->disconnected transition
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> (ttyACM0): device state
change: 9 -> 3 (reason 0)
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> (ttyACM0): deactivating
device (reason: 0).
Jul 26 12:01:10 c457826 NetworkManager[1058]: <debug> [1311674470.644883]
[nm-system.c:1349] flush_routes(): (ttyACM0) failed to lookup interface index
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Policy set 'Auto eth0'
(eth0) as default for IPv4 routing and DNS.
Jul 26 12:01:10 c457826 NetworkManager[1058]: <info> Policy set 'Auto eth0'
(eth0) as default for IPv4 routing and DNS.
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.182793]
[nm-netlink-monitor.c:117] link_msg_handler(): netlink link message: iface idx 4
flags 0x1003
Jul 26 12:01:36 c457826 wpa_supplicant[1183]: WPS-AP-AVAILABLE
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186436]
[wpa.c:443] wpa_parse_wpa_ie(): WPA IE: -------------------
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186481]
[wpa.c:444] wpa_parse_wpa_ie(): proto 0x2
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186505]
[wpa.c:445] wpa_parse_wpa_ie(): pw cipher 0xC
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186522]
[wpa.c:446] wpa_parse_wpa_ie(): gr cipher 0x4
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186537]
[wpa.c:447] wpa_parse_wpa_ie(): key mgmt 0x2
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186551]
[wpa.c:448] wpa_parse_wpa_ie(): capabilities 0x0
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186565]
[wpa.c:449] wpa_parse_wpa_ie(): # pmkid 0x0
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186584]
[wpa.c:450] wpa_parse_wpa_ie():
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186637]
[wpa.c:443] wpa_parse_wpa_ie(): WPA IE: -------------------
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186667]
[wpa.c:444] wpa_parse_wpa_ie(): proto 0x4
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186695]
[wpa.c:445] wpa_parse_wpa_ie(): pw cipher 0xC
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186723]
[wpa.c:446] wpa_parse_wpa_ie(): gr cipher 0x4
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186748]
[wpa.c:447] wpa_parse_wpa_ie(): key mgmt 0x2
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186772]
[wpa.c:448] wpa_parse_wpa_ie(): capabilities 0x0
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186799]
[wpa.c:449] wpa_parse_wpa_ie(): # pmkid 0x0
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.186829]
[wpa.c:450] wpa_parse_wpa_ie():
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.187068]
[nm-device-wifi.c:1619] wireless_qual_to_percent(): QL: qual 61/61/0x3D, level
-49/207/0xCF, noise 0/0/0x0, updated: 0x7 ** MAX: qual 70/70/0x46, level
-110/146/0x92, noise 0/0/0x0, updated: 0x4B
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.187114]
[nm-device-wifi.c:1679] wireless_qual_to_percent(): QL2: level_percent is 100.
max_level 146, level 146.
Jul 26 12:01:36 c457826 NetworkManager[1058]: <debug> [1311674496.187148]
[nm-device-wifi.c:1689] wireless_qual_to_percent(): QL: Final quality percent is
87 (87).
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.181287]
[nm-netlink-monitor.c:117] link_msg_handler(): netlink link message: iface idx 4
flags 0x1003
Jul 26 12:02:36 c457826 wpa_supplicant[1183]: WPS-AP-AVAILABLE
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.184917]
[wpa.c:443] wpa_parse_wpa_ie(): WPA IE: -------------------
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.184968]
[wpa.c:444] wpa_parse_wpa_ie(): proto 0x2
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.184999]
[wpa.c:445] wpa_parse_wpa_ie(): pw cipher 0xC
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185026]
[wpa.c:446] wpa_parse_wpa_ie(): gr cipher 0x4
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185056]
[wpa.c:447] wpa_parse_wpa_ie(): key mgmt 0x2
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185082]
[wpa.c:448] wpa_parse_wpa_ie(): capabilities 0x0
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185109]
[wpa.c:449] wpa_parse_wpa_ie(): # pmkid 0x0
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185136]
[wpa.c:450] wpa_parse_wpa_ie():
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185195]
[wpa.c:443] wpa_parse_wpa_ie(): WPA IE: -------------------
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185225]
[wpa.c:444] wpa_parse_wpa_ie(): proto 0x4
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185251]
[wpa.c:445] wpa_parse_wpa_ie(): pw cipher 0xC
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185277]
[wpa.c:446] wpa_parse_wpa_ie(): gr cipher 0x4
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185303]
[wpa.c:447] wpa_parse_wpa_ie(): key mgmt 0x2
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185330]
[wpa.c:448] wpa_parse_wpa_ie(): capabilities 0x0
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185356]
[wpa.c:449] wpa_parse_wpa_ie(): # pmkid 0x0
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185381]
[wpa.c:450] wpa_parse_wpa_ie():
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185609]
[nm-device-wifi.c:1619] wireless_qual_to_percent(): QL: qual 60/60/0x3C, level
-50/206/0xCE, noise 0/0/0x0, updated: 0x7 ** MAX: qual 70/70/0x46, level
-110/146/0x92, noise 0/0/0x0, updated: 0x4B
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185660]
[nm-device-wifi.c:1679] wireless_qual_to_percent(): QL2: level_percent is 100.
max_level 146, level 146.
Jul 26 12:02:36 c457826 NetworkManager[1058]: <debug> [1311674556.185691]
[nm-device-wifi.c:1689] wireless_qual_to_percent(): QL: Final quality percent is
85 (85).
Regards
-- HG
[
Date Prev][
Date Next] [
Thread Prev][
Thread Next]
[
Thread Index]
[
Date Index]
[
Author Index]
