GNOME.org
 

Re: No 3G after disconnecting

On Tue, 2011-07-26 at 12:17 +0200, wp1191918-hgvs wrote:
>  
> 
> 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>'

Is this where the modem-manager output ends?  Does it stop here and not
print out anything else?

Dan

> >>>> /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 
> _______________________________________________
> networkmanager-list mailing list
> networkmanager-list gnome org
> http://mail.gnome.org/mailman/listinfo/networkmanager-list
[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]