Marius Kotsbak <[email protected]> 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 _______________________________________________ networkmanager-list mailing list [email protected] http://mail.gnome.org/mailman/listinfo/networkmanager-list
