On Tue, 2011-07-26 at 12:17 +0200, wp1191918-hgvs wrote: > > > 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>'
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 > [email protected] > http://mail.gnome.org/mailman/listinfo/networkmanager-list _______________________________________________ networkmanager-list mailing list [email protected] http://mail.gnome.org/mailman/listinfo/networkmanager-list
