Package: modemmanager
Version: 0.4+git.20100624t180933.6e79d15-1
--- Please enter the report below this line. ---
Hi!
I have the same probem:
Since upgrade to modemmanager 0.4 (in testing) I can't dial/connect via my
Huawei K3520 (E169?). With my Nokia E72 attached via usb as modem I can
dial/connect fine.
Attached are my debug logs.
Running Debian testing only (no unstable/experimental).
Networkmanager version: 0.8-1
best regards
Achim
--- System information. ---
Architecture: i386
Kernel: Linux 2.6.32-5-686
Debian Release: squeeze/sid
500 testing security.debian.org
500 testing ftp.de.debian.org
--- Package information. ---
Depends (Version) | Installed
================================-+-============
libc6 (>= 2.7) | 2.11.2-2
libdbus-1-3 (>= 1.0.2) | 1.2.24-1
libdbus-glib-1-2 (>= 0.86) | 0.86-1
libglib2.0-0 (>= 2.22.0) | 2.24.1-1
libgudev-1.0-0 (>= 146) | 158-1
Recommends (Version) | Installed
=============================-+-===========
usb-modeswitch | 1.1.3-1
Package's Suggests field is empty.
[ 1204.536062] usb 2-2: new full speed USB device using uhci_hcd and address 4
[ 1204.756121] usb 2-2: New USB device found, idVendor=12d1, idProduct=1001
[ 1204.756128] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=1
[ 1204.756133] usb 2-2: Product: HUAWEI Mobile
[ 1204.756137] usb 2-2: Manufacturer: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
[ 1204.756142] usb 2-2: SerialNumber: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
[ 1204.756298] usb 2-2: configuration #1 chosen from 1 choice
[ 1204.761152] scsi10 : SCSI emulation for USB Mass Storage devices
[ 1204.761321] usb-storage: device found at 4
[ 1204.761326] usb-storage: waiting for device to settle before scanning
[ 1204.952116] usb 2-2: USB disconnect, address 4
[ 1205.688060] usb 2-2: new full speed USB device using uhci_hcd and address 5
[ 1205.849158] usb 2-2: New USB device found, idVendor=12d1, idProduct=1001
[ 1205.849164] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=1
[ 1205.849169] usb 2-2: Product: HUAWEI Mobile
[ 1205.849173] usb 2-2: Manufacturer: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
[ 1205.849178] usb 2-2: SerialNumber: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
[ 1205.849327] usb 2-2: configuration #1 chosen from 1 choice
[ 1205.855357] option 2-2:1.0: GSM modem (1-port) converter detected
[ 1205.855485] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB0
[ 1205.857270] option 2-2:1.1: GSM modem (1-port) converter detected
[ 1205.857387] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB1
[ 1205.859317] option 2-2:1.2: GSM modem (1-port) converter detected
[ 1205.859426] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB2
[ 1205.860197] scsi14 : SCSI emulation for USB Mass Storage devices
[ 1205.860393] usb-storage: device found at 5
[ 1205.860397] usb-storage: waiting for device to settle before scanning
[ 1210.861398] usb-storage: device scan complete
[ 1210.864373] scsi 14:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 1210.867362] scsi 14:0:0:1: Direct-Access HUAWEI SD Storage 2.31 PQ: 0 ANSI: 2
[ 1210.890339] sr0: scsi-1 drive
[ 1210.890522] sr 14:0:0:0: Attached scsi CD-ROM sr0
[ 1210.890645] sr 14:0:0:0: Attached scsi generic sg1 type 5
[ 1210.890931] sd 14:0:0:1: Attached scsi generic sg2 type 0
[ 1210.906333] sd 14:0:0:1: [sdb] Attached SCSI removable disk
[ 1221.672695] ISO 9660 Extensions: Microsoft Joliet Level 3
[ 1221.677692] ISOFS: changing to secondary root
[ 1325.232113] usb 2-2: USB disconnect, address 5
[ 1325.234005] option: option_instat_callback: error -108
[ 1325.234347] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
[ 1325.234371] option 2-2:1.0: device disconnected
[ 1325.234615] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
[ 1325.234649] option 2-2:1.1: device disconnected
[ 1325.234886] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
[ 1325.234919] option 2-2:1.2: device disconnected
[ 1325.239284] modem-manager[2895]: segfault at 60 ip 0806bceb sp bfa7a500 error 4 in modem-manager[8048000+3d000]
[ 1325.257637] scsi 14:0:0:0: rejecting I/O to dead device
[ 1331.924074] usb 2-2: new full speed USB device using uhci_hcd and address 6
[ 1332.089131] usb 2-2: New USB device found, idVendor=12d1, idProduct=1001
[ 1332.089137] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=1
[ 1332.089142] usb 2-2: Product: HUAWEI Mobile
[ 1332.089146] usb 2-2: Manufacturer: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
[ 1332.089150] usb 2-2: SerialNumber: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
[ 1332.089310] usb 2-2: configuration #1 chosen from 1 choice
[ 1332.095317] option 2-2:1.0: GSM modem (1-port) converter detected
[ 1332.095449] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB0
[ 1332.097286] option 2-2:1.1: GSM modem (1-port) converter detected
[ 1332.097393] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB1
[ 1332.099299] option 2-2:1.2: GSM modem (1-port) converter detected
[ 1332.099402] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB2
[ 1332.100168] scsi18 : SCSI emulation for USB Mass Storage devices
[ 1332.100434] usb-storage: device found at 6
[ 1332.100438] usb-storage: waiting for device to settle before scanning
[ 1337.101373] usb-storage: device scan complete
[ 1337.104346] scsi 18:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 1337.107336] scsi 18:0:0:1: Direct-Access HUAWEI SD Storage 2.31 PQ: 0 ANSI: 2
[ 1337.130313] sr0: scsi-1 drive
[ 1337.130496] sr 18:0:0:0: Attached scsi CD-ROM sr0
[ 1337.130620] sr 18:0:0:0: Attached scsi generic sg1 type 5
[ 1337.130906] sd 18:0:0:1: Attached scsi generic sg2 type 0
[ 1337.147301] sd 18:0:0:1: [sdb] Attached SCSI removable disk
[ 1347.679656] ISO 9660 Extensions: Microsoft Joliet Level 3
[ 1347.684661] ISOFS: changing to secondary root
[ 1404.840129] usb 2-2: USB disconnect, address 6
[ 1404.841470] option: option_instat_callback: error -108
[ 1404.841795] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
[ 1404.841833] option 2-2:1.0: device disconnected
[ 1404.842065] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
[ 1404.842097] option 2-2:1.1: device disconnected
[ 1404.842312] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
[ 1404.842344] option 2-2:1.2: device disconnected
[ 1404.882758] scsi 18:0:0:0: rejecting I/O to dead device
Connect Nokia E72 and dial via Network-manager/Modemmanager
[ 2048.644055] usb 1-2: new high speed USB device using ehci_hcd and address 10
[ 2048.777381] usb 1-2: New USB device found, idVendor=0421, idProduct=0223
[ 2048.777387] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 2048.777392] usb 1-2: Product: E72-1
[ 2048.777396] usb 1-2: Manufacturer: Nokia
[ 2048.777400] usb 1-2: SerialNumber: 355239032454295
[ 2048.777565] usb 1-2: configuration #1 chosen from 1 choice
[ 2048.780490] cdc_acm 1-2:1.1: ttyACM0: USB ACM device
[ 2048.781828] usb 1-2: bad CDC descriptors
[ 2048.781938] usb 1-2: bad CDC descriptors
[ 2048.783302] cdc_phonet: probe of 1-2:1.12 failed with error -22
[ 2070.094403] PPP generic driver version 2.4.2
[ 2070.492202] PPP BSD Compression module registered
[ 2070.569263] PPP Deflate Compression module registered
ben:~# modem-manager --debug
** Message: ModemManager (version 0.4) starting...
** Message: Loaded plugin AnyData
** Message: Loaded plugin Generic
** Message: Loaded plugin Nokia
** Message: Loaded plugin SimTech
** Message: Loaded plugin MotoC
** Message: Loaded plugin Ericsson MBM
** Message: Loaded plugin Novatel
** Message: Loaded plugin ZTE
** Message: Loaded plugin Huawei
** Message: Loaded plugin Option
** Message: Loaded plugin Longcheer
** Message: Loaded plugin Sierra
** Message: Loaded plugin Option High-Speed
** Message: Loaded plugin Gobi
** (modem-manager:2895): DEBUG: (tty/ttyS0): port's parent platform driver is not whitelisted
** (modem-manager:2895): DEBUG: (tty/ttyS1): port's parent platform driver is not whitelisted
** (modem-manager:2895): DEBUG: (tty/ttyS2): port's parent platform driver is not whitelisted
** (modem-manager:2895): DEBUG: (tty/ttyS3): port's parent platform driver is not whitelisted
** (modem-manager:2895): DEBUG: (net/pan0): could not get port's parent device
** (modem-manager:2895): DEBUG: (net/vboxnet0): could not get port's parent device
** (modem-manager:2895): DEBUG: (Huawei): (ttyUSB2) deferring support check
** Message: (ttyUSB0) opening serial device...
** (modem-manager:2895): DEBUG: <1279517454.553275> (ttyUSB0) device open count is 1 (open)
** (modem-manager:2895): DEBUG: (ttyUSB0): probe requested by plugin 'Huawei'
** (modem-manager:2895): DEBUG: (Huawei): (ttyUSB1) deferring support check
** (modem-manager:2895): DEBUG: <1279517454.655390> (ttyUSB0): --> 'AT+GCAP<CR>'
** (modem-manager:2895): DEBUG: <1279517455.456330> (ttyUSB0): <-- 'AT+GCAP<CR><CR><LF>+CME ERROR: SIM PIN required<CR><LF>'
** (modem-manager:2895): DEBUG: Got failure code 11: SIM PIN required
** (modem-manager:2895): DEBUG: <1279517455.456604> (ttyUSB0): --> 'AT+GCAP<CR>'
** (modem-manager:2895): DEBUG: <1279517456.257482> (ttyUSB0): <-- 'AT+GCAP<CR><CR><LF>+CME ERROR: SIM PIN required<CR><LF>'
** (modem-manager:2895): DEBUG: Got failure code 11: SIM PIN required
** (modem-manager:2895): DEBUG: <1279517456.257673> (ttyUSB0): --> 'AT+GCAP<CR>'
** (modem-manager:2895): DEBUG: <1279517457.58547> (ttyUSB0): <-- 'AT+GCAP<CR><CR><LF>+CME ERROR: SIM PIN required<CR><LF>'
** (modem-manager:2895): DEBUG: Got failure code 11: SIM PIN required
** (modem-manager:2895): DEBUG: <1279517457.58755> (ttyUSB0): --> 'ATI<CR>'
** (modem-manager:2895): DEBUG: <1279517457.459263> (ttyUSB0): <-- 'ATI<CR><CR><LF>Manufacturer: huawei<CR><LF>Model: K3520<CR><LF>Revision: 11.314.21.31.00<CR><LF>IMEI: 353284020403161<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517457.459436> (ttyUSB0) device open count is 0 (close)
** Message: (ttyUSB0) closing serial device...
** Message: (ttyUSB0) type primary claimed by /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** Message: (ttyUSB0) opening serial device...
** (modem-manager:2895): DEBUG: <1279517457.470382> (ttyUSB0) device open count is 1 (open)
** (modem-manager:2895): DEBUG: <1279517457.470446> (ttyUSB0) device open count is 2 (open)
** Message: (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2 claimed port ttyUSB0
** (modem-manager:2895): DEBUG: Added modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** (modem-manager:2895): DEBUG: (tty/ttyUSB0): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** (modem-manager:2895): DEBUG: (tty/ttyUSB1): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** (modem-manager:2895): DEBUG: <1279517457.470859> (ttyUSB0): --> 'AT+CPIN?<CR>'
** (modem-manager:2895): DEBUG: <1279517457.482538> (ttyUSB0): <-- 'AT+CPIN?'
** (modem-manager:2895): DEBUG: <1279517457.487197> (ttyUSB0): <-- '<CR><CR><LF>+CPIN: SIM PIN<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: get_unlock_retries: pin type 'sim-pin'
** (modem-manager:2895): DEBUG: <1279517457.487377> (ttyUSB0) device open count is 3 (open)
** (modem-manager:2895): DEBUG: <1279517457.487476> (ttyUSB0) device open count is 2 (close)
** (modem-manager:2895): DEBUG: (tty/ttyUSB1): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** (modem-manager:2895): DEBUG: <1279517457.487639> (ttyUSB0): --> 'ATE0<CR>'
** (modem-manager:2895): DEBUG: <1279517457.493129> (ttyUSB0): <-- 'ATE0'
** (modem-manager:2895): DEBUG: <1279517457.497125> (ttyUSB0): <-- '<CR><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517457.497239> (ttyUSB0): --> 'AT+CGSN<CR>'
** (modem-manager:2895): DEBUG: <1279517457.511201> (ttyUSB0): <-- '<CR><LF>353284020403161<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517457.511364> (ttyUSB0) device open count is 1 (close)
** (modem-manager:2895): DEBUG: <1279517457.511408> (ttyUSB0): --> 'ATE0<CR>'
** (modem-manager:2895): DEBUG: <1279517457.519194> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517457.519297> (ttyUSB0): --> 'AT^CPIN?<CR>'
** (modem-manager:2895): DEBUG: <1279517457.532238> (ttyUSB0): <-- '<CR><LF>^CPIN: SIM PIN,3,10,3,10,3<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517457.532393> (ttyUSB0) device open count is 0 (close)
** Message: (ttyUSB0) closing serial device...
** (modem-manager:2895): DEBUG: (ttyUSB2): re-checking support...
** Message: (ttyUSB2) opening serial device...
** (modem-manager:2895): DEBUG: <1279517457.551378> (ttyUSB2) device open count is 1 (open)
** (modem-manager:2895): DEBUG: (ttyUSB1): re-checking support...
** Message: (ttyUSB1) opening serial device...
** (modem-manager:2895): DEBUG: <1279517457.560394> (ttyUSB1) device open count is 1 (open)
** (modem-manager:2895): DEBUG: <1279517465.86> (ttyUSB2) device open count is 0 (close)
** Message: (ttyUSB2) closing serial device...
** (modem-manager:2895): DEBUG: <1279517465.5683> (ttyUSB1) device open count is 0 (close)
** Message: (ttyUSB1) closing serial device...
** Message: (ttyUSB2) type ignored claimed by /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** Message: (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2 claimed port ttyUSB2
** (modem-manager:2895): DEBUG: (tty/ttyUSB1): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** Message: (ttyUSB1) type ignored claimed by /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** Message: (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2 claimed port ttyUSB1
** (modem-manager:2895): DEBUG: Exported modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2 as /org/freedesktop/ModemManager/Modems/0
** (modem-manager:2895): DEBUG: (/org/freedesktop/ModemManager/Modems/0): data port is ttyUSB0
** Message: (ttyUSB0) opening serial device...
** (modem-manager:2895): DEBUG: <1279517569.789010> (ttyUSB0) device open count is 1 (open)
** (modem-manager:2895): DEBUG: <1279517569.789090> (ttyUSB0): --> 'AT+CPIN="1234"<CR>'
** (modem-manager:2895): DEBUG: <1279517569.895220> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517569.895363> (ttyUSB0): --> 'AT+CPIN?<CR>'
** (modem-manager:2895): DEBUG: <1279517569.907221> (ttyUSB0): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
** Message: Modem /org/freedesktop/ModemManager/Modems/0: unlock no longer required
** Message: Modem /org/freedesktop/ModemManager/Modems/0: # unlock retries for (null) is 0
** (modem-manager:2895): DEBUG: <1279517569.907672> (ttyUSB0) device open count is 0 (close)
** Message: (ttyUSB0) closing serial device...
** Message: (ttyUSB0) opening serial device...
** (modem-manager:2895): DEBUG: <1279517569.917025> (ttyUSB0) device open count is 1 (open)
** (modem-manager:2895): DEBUG: <1279517569.917111> Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
** (modem-manager:2895): DEBUG: <1279517570.19076> (ttyUSB0): --> 'ATZ E0 V1<CR>'
** (modem-manager:2895): DEBUG: <1279517570.68243> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517570.68395> (ttyUSB0): --> 'ATE0<CR>'
** (modem-manager:2895): DEBUG: <1279517570.75194> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517570.75296> (ttyUSB0): --> 'AT+CMEE=1<CR>'
** (modem-manager:2895): DEBUG: <1279517570.88406> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517570.88517> (ttyUSB0): --> 'ATX4 &C1<CR>'
** (modem-manager:2895): DEBUG: <1279517570.116588> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517570.116703> (ttyUSB0): --> 'AT+CFUN=1<CR>'
** (modem-manager:2895): DEBUG: <1279517571.267223> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.267409> (ttyUSB0): --> 'AT+IFC=1,1<CR>'
** (modem-manager:2895): DEBUG: <1279517571.288237> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.288351> (ttyUSB0): --> 'AT+GMI<CR>'
** (modem-manager:2895): DEBUG: <1279517571.299204> (ttyUSB0): <-- '<CR><LF>huawei<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.299341> (ttyUSB0): --> 'AT+GMM<CR>'
** (modem-manager:2895): DEBUG: <1279517571.403298> (ttyUSB0): <-- '<CR><LF>K3520<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.403436> (ttyUSB0): --> 'AT+GMR<CR>'
** (modem-manager:2895): DEBUG: <1279517571.415205> (ttyUSB0): <-- '<CR><LF>11.314.21.31.00<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.415323> (ttyUSB0): --> 'AT+CGMI<CR>'
** (modem-manager:2895): DEBUG: <1279517571.428245> (ttyUSB0): <-- '<CR><LF>huawei<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.428371> (ttyUSB0): --> 'AT+CGMM<CR>'
** (modem-manager:2895): DEBUG: <1279517571.448236> (ttyUSB0): <-- '<CR><LF>K3520<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.448364> (ttyUSB0): --> 'AT+CGMR<CR>'
** (modem-manager:2895): DEBUG: <1279517571.459201> (ttyUSB0): <-- '<CR><LF>11.314.21.31.00<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.459332> (ttyUSB0): --> 'AT^SYSCFG?<CR>'
** (modem-manager:2895): DEBUG: <1279517571.480238> (ttyUSB0): <-- '<CR><LF>^SYSCFG:2,0,3FFFFFFF,1,2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.480414> (ttyUSB0): --> 'AT+CSCS=?<CR>'
** (modem-manager:2895): DEBUG: <1279517571.516239> (ttyUSB0): <-- '<CR><LF>+CSCS: ("IRA","GSM","UCS2")<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.516493> (ttyUSB0): --> 'AT+CSCS="UCS2"<CR>'
** (modem-manager:2895): DEBUG: <1279517571.536244> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.536364> (ttyUSB0): --> 'AT+CSCS?<CR>'
** (modem-manager:2895): DEBUG: <1279517571.551206> (ttyUSB0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.551400> Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> enabled)
** (modem-manager:2895): DEBUG: <1279517571.551669> (ttyUSB0): --> 'AT+CREG=2<CR>'
** (modem-manager:2895): DEBUG: <1279517571.568237> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.568370> (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:2895): DEBUG: <1279517571.583207> (ttyUSB0): <-- '<CR><LF>+CREG: 2,2,00,0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: CS registration state changed: 2
** (modem-manager:2895): DEBUG: <1279517571.583373> Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabled -> searching)
** (modem-manager:2895): DEBUG: <1279517571.583491> (ttyUSB0): --> 'AT+CGREG=2<CR>'
** (modem-manager:2895): DEBUG: <1279517571.607196> (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: <1279517571.607317> (ttyUSB0): --> 'AT+CGREG?<CR>'
** (modem-manager:2895): DEBUG: <1279517571.624248> (ttyUSB0): <-- '<CR><LF>+CGREG: 2,2,00,0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:2895): DEBUG: PS registration state changed: 2
** (modem-manager:2895): DEBUG: <1279517573.320311> (ttyUSB0): network_mode => 1
** (modem-manager:2895): DEBUG: <1279517573.320311> (ttyUSB0): number => "*99#"
** (modem-manager:2895): DEBUG: <1279517573.320311> (ttyUSB0): apn => "surfo2"
** (modem-manager:2895): DEBUG: <1279517573.320311> (ttyUSB0): pin => "1234"
** (modem-manager:2895): DEBUG: <1279517573.320478> (ttyUSB0): simple connect state 0
** (modem-manager:2895): DEBUG: <1279517573.320560> (ttyUSB0): simple connect state 2
** (modem-manager:2895): DEBUG: <1279517573.320652> (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:2895): DEBUG: <1279517573.881682> Modem /org/freedesktop/ModemManager/Modems/0: state changed (searching -> disabled)
** (modem-manager:2895): DEBUG: Removed modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-2
** (modem-manager:2895): CRITICAL **: mm_callback_info_schedule: assertion `info->pending_id == 0' failed
** (modem-manager:2895): DEBUG: <1279517573.881972> (ttyUSB0) device open count is 0 (close)
** Message: (ttyUSB0) closing serial device...
(modem-manager:2895): GLib-GObject-CRITICAL **: g_type_instance_get_private: assertion `instance != NULL && instance->g_class != NULL' failed
Speicherzugriffsfehler
ben:~# ^C
ben:~#
ben:~# modem-manager --debug
** Message: ModemManager (version 0.4) starting...
** (modem-manager:3314): WARNING **: Could not acquire the org.freedesktop.ModemManager service as it is already taken. Return: 3
ben:~# killall -TERM modem-manager
ben:~#
ben:~# modem-manager --debug
** Message: ModemManager (version 0.4) starting...
** Message: Loaded plugin AnyData
** Message: Loaded plugin Generic
** Message: Loaded plugin Nokia
** Message: Loaded plugin SimTech
** Message: Loaded plugin MotoC
** Message: Loaded plugin Ericsson MBM
** Message: Loaded plugin Novatel
** Message: Loaded plugin ZTE
** Message: Loaded plugin Huawei
** Message: Loaded plugin Option
** Message: Loaded plugin Longcheer
** Message: Loaded plugin Sierra
** Message: Loaded plugin Option High-Speed
** Message: Loaded plugin Gobi
** (modem-manager:3316): DEBUG: (tty/ttyS0): port's parent platform driver is not whitelisted
** (modem-manager:3316): DEBUG: (tty/ttyS1): port's parent platform driver is not whitelisted
** (modem-manager:3316): DEBUG: (tty/ttyS2): port's parent platform driver is not whitelisted
** (modem-manager:3316): DEBUG: (tty/ttyS3): port's parent platform driver is not whitelisted
** (modem-manager:3316): DEBUG: (net/pan0): could not get port's parent device
** (modem-manager:3316): DEBUG: (net/vboxnet0): could not get port's parent device
** Message: (ttyACM0) opening serial device...
** (modem-manager:3316): DEBUG: <1279518297.522010> (ttyACM0) device open count is 1 (open)
** (modem-manager:3316): DEBUG: (ttyACM0): probe requested by plugin 'Nokia'
** (modem-manager:3316): DEBUG: <1279518297.621874> (ttyACM0): --> 'AT+GCAP<CR>'
** (modem-manager:3316): DEBUG: <1279518298.422807> (ttyACM0): <-- 'AT+GCAP<CR><CR><LF>+GCAP: +CGSM,+DS,+W<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518298.423023> (ttyACM0) device open count is 0 (close)
** Message: (ttyACM0) closing serial device...
** Message: (ttyACM0) type primary claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-2
** Message: (ttyACM0) opening serial device...
** (modem-manager:3316): DEBUG: <1279518298.431298> (ttyACM0) device open count is 1 (open)
** (modem-manager:3316): DEBUG: <1279518298.431363> (ttyACM0) device open count is 2 (open)
** Message: (Nokia): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-2 claimed port ttyACM0
** (modem-manager:3316): DEBUG: Added modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-2
** (modem-manager:3316): DEBUG: (tty/ttyACM0): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-2
** (modem-manager:3316): DEBUG: <1279518298.431731> (ttyACM0): --> 'AT+CPIN?<CR>'
** (modem-manager:3316): DEBUG: <1279518298.443834> (ttyACM0): <-- 'AT+CPIN?'
** (modem-manager:3316): DEBUG: <1279518298.448200> (ttyACM0): <-- '<CR><CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518298.448342> (ttyACM0) device open count is 1 (close)
** (modem-manager:3316): DEBUG: Exported modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-2 as /org/freedesktop/ModemManager/Modems/0
** (modem-manager:3316): DEBUG: (/org/freedesktop/ModemManager/Modems/0): data port is ttyACM0
** (modem-manager:3316): DEBUG: <1279518298.448737> (ttyACM0): --> 'ATE0<CR>'
** (modem-manager:3316): DEBUG: <1279518298.454660> (ttyACM0): <-- 'ATE0<CR><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518298.454944> (ttyACM0): --> 'AT+CGSN<CR>'
** (modem-manager:3316): DEBUG: <1279518298.464828> (ttyACM0): <-- '<CR><LF>355239032454295<CR><LF><CR><LF>OK<CR><LF>'
** Message: Modem /org/freedesktop/ModemManager/Modems/0: Equipment identifier set (355239032454295)
** (modem-manager:3316): DEBUG: <1279518298.465021> (ttyACM0) device open count is 0 (close)
** Message: (ttyACM0) closing serial device...
** Message: (ttyACM0) opening serial device...
** (modem-manager:3316): DEBUG: <1279518314.671211> (ttyACM0) device open count is 1 (open)
** (modem-manager:3316): DEBUG: <1279518314.671345> Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
** (modem-manager:3316): DEBUG: <1279518314.771889> (ttyACM0): --> 'ATZ E0 V1<CR>'
** (modem-manager:3316): DEBUG: <1279518314.787199> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.787388> (ttyACM0): --> 'ATE0<CR>'
** (modem-manager:3316): DEBUG: <1279518314.797883> (ttyACM0): <-- 'ATE0<CR><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.797992> (ttyACM0): --> 'AT+CMEE=1<CR>'
** (modem-manager:3316): DEBUG: <1279518314.816238> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.816347> (ttyACM0): --> 'ATX4 &C1<CR>'
** (modem-manager:3316): DEBUG: <1279518314.827196> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.827294> (ttyACM0): --> 'AT+IFC=1,1<CR>'
** (modem-manager:3316): DEBUG: <1279518314.847191> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.847302> (ttyACM0): --> 'AT+GMI<CR>'
** (modem-manager:3316): DEBUG: <1279518314.859197> (ttyACM0): <-- '<CR><LF>Nokia<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.859318> (ttyACM0): --> 'AT+GMM<CR>'
** (modem-manager:3316): DEBUG: <1279518314.872488> (ttyACM0): <-- '<CR><LF>Nokia E72-1<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.872614> (ttyACM0): --> 'AT+GMR<CR>'
** (modem-manager:3316): DEBUG: <1279518314.896195> (ttyACM0): <-- '<CR><LF>V ICPR82_09w36.4<CR><LF>01-10-09<CR><LF>RM-530<CR><LF>(c) Nokia<CR><LF><CR><LF>021.024<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.896337> (ttyACM0): --> 'AT+CGMI<CR>'
** (modem-manager:3316): DEBUG: <1279518314.911196> (ttyACM0): <-- '<CR><LF>Nokia<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.911312> (ttyACM0): --> 'AT+CGMM<CR>'
** (modem-manager:3316): DEBUG: <1279518314.923190> (ttyACM0): <-- '<CR><LF>Nokia E72-1<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.923310> (ttyACM0): --> 'AT+CGMR<CR>'
** (modem-manager:3316): DEBUG: <1279518314.940248> (ttyACM0): <-- '<CR><LF>V ICPR82_09w36.4<CR><LF>01-10-09<CR><LF>RM-530<CR><LF>(c) Nokia<CR><LF><CR><LF>021.024<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.940399> (ttyACM0): --> 'AT+CSCS=?<CR>'
** (modem-manager:3316): DEBUG: <1279518314.955206> (ttyACM0): <-- '<CR><LF>+CSCS: ("UCS2","GSM","PCCP437","PCDN","IRA","8859-1","HEX")<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.955510> (ttyACM0): --> 'AT+CSCS="UCS2"<CR>'
** (modem-manager:3316): DEBUG: <1279518314.976247> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.976365> (ttyACM0): --> 'AT+CSCS?<CR>'
** (modem-manager:3316): DEBUG: <1279518314.991233> (ttyACM0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518314.991438> Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> enabled)
** (modem-manager:3316): DEBUG: <1279518314.991710> (ttyACM0): --> 'AT+CREG=2<CR>'
** (modem-manager:3316): DEBUG: <1279518315.8247> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518315.8382> (ttyACM0): --> 'AT+CREG?<CR>'
** (modem-manager:3316): DEBUG: <1279518315.24243> (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"A2ED","9CA4"<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: CS registration state changed: 1
** (modem-manager:3316): DEBUG: <1279518315.24446> Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabled -> registered)
** (modem-manager:3316): DEBUG: <1279518315.24568> (ttyACM0): --> 'AT+CGREG=2<CR>'
** (modem-manager:3316): DEBUG: <1279518315.43190> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518315.43387> (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
** (modem-manager:3316): DEBUG: <1279518315.67198> (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"26207",0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518315.67501> (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
** (modem-manager:3316): DEBUG: <1279518315.96242> (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"o2 - de",0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518315.96566> (ttyACM0): --> 'AT+CSQ<CR>'
** (modem-manager:3316): DEBUG: <1279518315.108655> (ttyACM0): <-- '<CR><LF>+CSQ: 10,99<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518315.108948> (ttyACM0): --> 'AT+CGREG?<CR>'
** (modem-manager:3316): DEBUG: <1279518315.128243> (ttyACM0): <-- '<CR><LF>+CGREG: 2,0,"A2ED","9CA4"<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518315.949849> (ttyACM0): network_mode => 1
** (modem-manager:3316): DEBUG: <1279518315.949849> (ttyACM0): number => "*99#"
** (modem-manager:3316): DEBUG: <1279518315.949849> (ttyACM0): apn => "surfo2"
** (modem-manager:3316): DEBUG: <1279518315.950000> (ttyACM0): simple connect state 0
** (modem-manager:3316): DEBUG: <1279518315.950081> (ttyACM0): simple connect state 2
** (modem-manager:3316): DEBUG: <1279518315.950174> (ttyACM0): --> 'AT+CREG?<CR>'
** (modem-manager:3316): DEBUG: <1279518315.967218> (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"A2ED","9CA4"<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518315.967386> (ttyACM0): simple connect state 4
** (modem-manager:3316): DEBUG: <1279518315.967478> (ttyACM0): --> 'AT+CGDCONT?<CR>'
** (modem-manager:3316): DEBUG: <1279518315.987197> (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP",,,0,0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518315.987364> (ttyACM0): --> 'AT+CGDCONT=?<CR>'
** (modem-manager:3316): DEBUG: <1279518316.7213> (ttyACM0): <-- '<CR><LF>+CGDCONT: (1-3),"IP",,,(0),(0)<CR><LF>+CGDCONT: (1-3),"IPV6",,,(0),(0)<CR><LF>+CGDCONT: (1-3),"PPP",,,(0),(0)<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518316.7385> (ttyACM0): --> 'AT+CGDCONT=1,"IP","surfo2"<CR>'
** (modem-manager:3316): DEBUG: <1279518316.39185> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:3316): DEBUG: <1279518316.39305> (ttyACM0): simple connect state 5
** (modem-manager:3316): DEBUG: <1279518316.39396> Modem /org/freedesktop/ModemManager/Modems/0: state changed (registered -> connecting)
** (modem-manager:3316): DEBUG: <1279518316.39487> (ttyACM0): --> 'ATD*99***1#<CR>'
** (modem-manager:3316): DEBUG: <1279518318.483237> (ttyACM0): <-- '<CR><LF>CONNECT<CR><LF>~\-1}#\-64!}!} } }2}#}$\-64#}!}$}%\-36}"}&} }*} } g}%~'
** (modem-manager:3316): DEBUG: <1279518318.483369> (ttyACM0): port now connected
** (modem-manager:3316): DEBUG: <1279518318.483449> Modem /org/freedesktop/ModemManager/Modems/0: state changed (connecting -> connected)
** (modem-manager:3316): DEBUG: <1279518318.483524> (ttyACM0): simple connect state 6
** (modem-manager:3316): DEBUG: <1279518318.639249> (ttyACM0): <-- '~\-1}#\-64!}!} } }2}#}$\-64#}!}$}%\-36}"}&} }*} } g}%~'
** (modem-manager:3316): DEBUG: (net/ppp0): could not get port's parent device
^C
** Message: Caught signal 2, shutting down...
** (modem-manager:3316): DEBUG: <1279518334.143382> Modem /org/freedesktop/ModemManager/Modems/0: state changed (connected -> disconnecting)
** (modem-manager:3316): DEBUG: <1279518335.144635> (ttyACM0): port now disconnected
** (modem-manager:3316): DEBUG: <1279518335.144871> (ttyACM0): --> 'AT+CGACT=0,1<CR>'
** (modem-manager:3316): DEBUG: <1279518338.408411> Modem /org/freedesktop/ModemManager/Modems/0: state changed (disconnecting -> registered)
** (modem-manager:3316): DEBUG: <1279518338.408711> Modem /org/freedesktop/ModemManager/Modems/0: state changed (registered -> disabling)
** (modem-manager:3316): DEBUG: <1279518338.408783> (ttyACM0) device open count is 0 (close)
** Message: (ttyACM0) closing serial device...
** (modem-manager:3316): DEBUG: <1279518338.408896> Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabling -> disabled)
** (modem-manager:3316): DEBUG: Removed modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-2
ben:~#
ben:~# NM_PPP_DEBUG=1 /usr/sbin/NetworkManager --no-daemon
NetworkManager: <info> starting...
NetworkManager: <info> modem-manager is now available
NetworkManager: SCPlugin-Ifupdown: init!
NetworkManager: SCPlugin-Ifupdown: update_system_hostname
NetworkManager: SCPluginIfupdown: management mode: unmanaged
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0, iface: eth0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0, iface: eth0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/wlan0, iface: wlan0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/pan0, iface: pan0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/pan0, iface: pan0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/vboxnet0, iface: vboxnet0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/vboxnet0, iface: vboxnet0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: end _init.
NetworkManager: Loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list.
NetworkManager: Loaded plugin keyfile: (c) 2007 - 2008 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
NetworkManager: <info> Found wlan radio killswitch rfkill2 (at /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/ieee80211/phy0/rfkill2) (driver <unknown>)
NetworkManager: <info> WiFi enabled by radio killswitch; enabled by state file
NetworkManager: <info> WWAN enabled by radio killswitch; disabled by state file
NetworkManager: SCPlugin-Ifupdown: (168580896) ... get_connections.
NetworkManager: SCPlugin-Ifupdown: (168580896) ... get_connections (managed=false): return empty list.
NetworkManager: Ifupdown: get unmanaged devices count: 0
NetworkManager: <info> (eth0): carrier is OFF
NetworkManager: <info> (eth0): new Ethernet device (driver: 'e1000e')
NetworkManager: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
NetworkManager: <info> (eth0): now managed
NetworkManager: <info> (eth0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (eth0): bringing up device.
NetworkManager: <info> (eth0): preparing device.
NetworkManager: <info> (eth0): deactivating device (reason: 2).
NetworkManager: <info> (wlan0): driver supports SSID scans (scan_capa 0x01).
NetworkManager: <info> (wlan0): new 802.11 WiFi device (driver: 'iwl3945')
NetworkManager: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/1
NetworkManager: <info> (wlan0): now managed
NetworkManager: <info> (wlan0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (wlan0): bringing up device.
NetworkManager: <info> (wlan0): preparing device.
NetworkManager: <info> (wlan0): deactivating device (reason: 2).
NetworkManager: <WARN> device_creator(): /sys/devices/virtual/net/pan0: couldn't determine device driver; ignoring...
NetworkManager: <WARN> device_creator(): /sys/devices/virtual/net/vboxnet0: couldn't determine device driver; ignoring...
/sbin/ifup: interface lo already configured
NetworkManager: <info> (wlan0): supplicant interface state: starting -> ready
NetworkManager: <info> (wlan0): device state change: 2 -> 3 (reason 42)
NetworkManager: <info> (ttyUSB0): new GSM device (driver: 'option1')
NetworkManager: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/2
NetworkManager: <info> (ttyUSB0): now managed
NetworkManager: <info> (ttyUSB0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (ttyUSB0): deactivating device (reason: 2).
NetworkManager: <info> (ttyUSB0): device state change: 2 -> 3 (reason 0)
NetworkManager: <info> Activation (ttyUSB0) starting connection 'surfo2'
NetworkManager: <info> (ttyUSB0): device state change: 3 -> 4 (reason 0)
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <WARN> stage1_enable_done(): GSM modem enable failed: (32) SIM PIN required
NetworkManager: <info> (ttyUSB0): device state change: 4 -> 6 (reason 0)
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> (ttyUSB0): device state change: 6 -> 4 (reason 0)
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <WARN> stage1_enable_done(): GSM modem enable failed: (32) SIM PIN required
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info> (ttyUSB0): now unmanaged
NetworkManager: <info> (ttyUSB0): device state change: 4 -> 1 (reason 36)
NetworkManager: <info> (ttyUSB0): deactivating device (reason: 36).
NetworkManager: <info> (ttyUSB0): cleaning up...
NetworkManager: <info> (ttyUSB0): taking down device.
NetworkManager: <info> modem manager disappeared
NetworkManager: <info> Trying to start the modem-manager...
NetworkManager: <info> modem-manager is now available
NetworkManager: <info> (ttyUSB0): new GSM device (driver: 'option1')
NetworkManager: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/3
NetworkManager: <info> (ttyUSB0): now managed
NetworkManager: <info> (ttyUSB0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (ttyUSB0): deactivating device (reason: 2).
NetworkManager: <info> (ttyUSB0): device state change: 2 -> 3 (reason 0)
^C
NetworkManager: <WARN> nm_signal_handler(): Caught signal 2, shutting down normally.
NetworkManager: <info> (eth0): cleaning up...
NetworkManager: <info> (eth0): taking down device.
NetworkManager: <info> (wlan0): taking down device.
NetworkManager: <info> (ttyUSB0): now unmanaged
NetworkManager: <info> (ttyUSB0): device state change: 3 -> 1 (reason 36)
NetworkManager: <info> (ttyUSB0): cleaning up...
NetworkManager: <info> (ttyUSB0): taking down device.
(NetworkManager:2896): GLib-GObject-CRITICAL **: g_object_unref: assertion `G_IS_OBJECT (object)' failed
NetworkManager: <info> exiting (success)
ben:~#
ben:~# NM_PPP_DEBUG=1 /usr/sbin/NetworkManager --no-daemon
NetworkManager: <info> starting...
NetworkManager: <info> modem-manager is now available
NetworkManager: SCPlugin-Ifupdown: init!
NetworkManager: SCPlugin-Ifupdown: update_system_hostname
NetworkManager: SCPluginIfupdown: management mode: unmanaged
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0, iface: eth0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0, iface: eth0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/wlan0, iface: wlan0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/pan0, iface: pan0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/pan0, iface: pan0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/vboxnet0, iface: vboxnet0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/vboxnet0, iface: vboxnet0): no ifupdown configuration found.
NetworkManager: SCPlugin-Ifupdown: end _init.
NetworkManager: Loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list.
NetworkManager: Loaded plugin keyfile: (c) 2007 - 2008 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
NetworkManager: <info> Found wlan radio killswitch rfkill2 (at /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/ieee80211/phy0/rfkill2) (driver <unknown>)
NetworkManager: <info> WiFi enabled by radio killswitch; enabled by state file
NetworkManager: <info> WWAN enabled by radio killswitch; disabled by state file
NetworkManager: SCPlugin-Ifupdown: (138950432) ... get_connections.
NetworkManager: SCPlugin-Ifupdown: (138950432) ... get_connections (managed=false): return empty list.
NetworkManager: Ifupdown: get unmanaged devices count: 0
NetworkManager: <info> (eth0): carrier is OFF
NetworkManager: <info> (eth0): new Ethernet device (driver: 'e1000e')
NetworkManager: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
NetworkManager: <info> (eth0): now managed
NetworkManager: <info> (eth0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (eth0): bringing up device.
NetworkManager: <info> (eth0): preparing device.
NetworkManager: <info> (eth0): deactivating device (reason: 2).
NetworkManager: <info> (wlan0): driver supports SSID scans (scan_capa 0x01).
NetworkManager: <info> (wlan0): new 802.11 WiFi device (driver: 'iwl3945')
NetworkManager: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/1
NetworkManager: <info> (wlan0): now managed
NetworkManager: <info> (wlan0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (wlan0): bringing up device.
NetworkManager: <info> (wlan0): preparing device.
NetworkManager: <info> (wlan0): deactivating device (reason: 2).
NetworkManager: <WARN> device_creator(): /sys/devices/virtual/net/pan0: couldn't determine device driver; ignoring...
NetworkManager: <WARN> device_creator(): /sys/devices/virtual/net/vboxnet0: couldn't determine device driver; ignoring...
/sbin/ifup: interface lo already configured
NetworkManager: <info> (wlan0): supplicant interface state: starting -> ready
NetworkManager: <info> (wlan0): device state change: 2 -> 3 (reason 42)
NetworkManager: <info> (ttyACM0): new GSM device (driver: 'cdc_acm')
NetworkManager: <info> (ttyACM0): exported as /org/freedesktop/NetworkManager/Devices/2
NetworkManager: <info> (ttyACM0): now managed
NetworkManager: <info> (ttyACM0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (ttyACM0): deactivating device (reason: 2).
NetworkManager: <info> (ttyACM0): device state change: 2 -> 3 (reason 0)
NetworkManager: <info> Activation (ttyACM0) starting connection 'surfo2'
NetworkManager: <info> (ttyACM0): device state change: 3 -> 4 (reason 0)
NetworkManager: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info> Activation (ttyACM0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <info> Activation (ttyACM0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <info> (ttyACM0): device state change: 4 -> 5 (reason 0)
NetworkManager: <info> Activation (ttyACM0) Stage 2 of 5 (Device Configure) successful.
NetworkManager: <info> Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <info> Activation (ttyACM0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <info> Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <info> (ttyACM0): device state change: 5 -> 7 (reason 0)
NetworkManager: <info> Starting pppd connection
NetworkManager: <debug> [1279518318.528439] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock nodefaultroute debug ttyACM0 noipdefault noauth usepeerdns lcp-echo-failure 0 lcp-echo-interval 0 ipparam /org/freedesktop/NetworkManager/PPP/0 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so
NetworkManager: <debug> [1279518318.595303] nm_ppp_manager_start(): ppp started with pid 3412
NetworkManager: <info> Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) scheduled...
NetworkManager: <info> Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager: <info> Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) started...
NetworkManager: <info> Activation (ttyACM0) Stage 4 of 5 (IP6 Configure Get) complete.
Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
** Message: nm-ppp-plugin: (plugin_init): initializing
** Message: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
using channel 1
Using interface ppp0
Connect: ppp0 <--> /dev/ttyACM0
** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x98840c85> <pcomp> <accomp>]
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
rcvd [LCP ConfRej id=0x1 <magic 0x98840c85> <pcomp> <accomp>]
sent [LCP ConfReq id=0x2 <asyncmap 0x0>]
rcvd [LCP ConfAck id=0x2 <asyncmap 0x0>]
rcvd [LCP ConfReq id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>]
sent [LCP ConfAck id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>]
** Message: nm-ppp-plugin: (nm_phasechange): status 6 / phase 'authenticate'
** Message: nm-ppp-plugin: (get_credentials): passwd-hook, requesting credentials...
** Message: nm-ppp-plugin: (get_credentials): got credentials from NetworkManager
sent [PAP AuthReq id=0x1 user="" password=<hidden>]
rcvd [PAP AuthAck id=0x1 ""]
PAP authentication succeeded
** Message: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
rcvd [IPCP ConfReq id=0x0 <addr 10.6.6.6>]
sent [IPCP ConfAck id=0x0 <addr 10.6.6.6>]
rcvd [LCP ProtRej id=0x0 80 fd 01 01 00 0f 1a 04 78 00 18 04 78 00 15 03 2f]
Protocol-Reject for 'Compression Control Protocol' (0x80fd) received
rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
rcvd [IPCP ConfNak id=0x2 <addr 10.141.84.97> <ms-dns1 193.189.244.225> <ms-dns3 193.189.244.206>]
sent [IPCP ConfReq id=0x3 <addr 10.141.84.97> <ms-dns1 193.189.244.225> <ms-dns3 193.189.244.206>]
rcvd [IPCP ConfAck id=0x3 <addr 10.141.84.97> <ms-dns1 193.189.244.225> <ms-dns3 193.189.244.206>]
Cannot determine ethernet address for proxy ARP
local IP address 10.141.84.97
remote IP address 10.6.6.6
primary DNS address 193.189.244.225
secondary DNS address 193.189.244.206
** Message: nm-ppp-plugin: (nm_phasechange): status 9 / phase 'running'
** Message: nm-ppp-plugin: (nm_ip_up): ip-up event
** Message: nm-ppp-plugin: (nm_ip_up): sending Ip4Config to NetworkManager...
NetworkManager: <info> PPP manager(IP Config Get) reply received.
Script /etc/ppp/ip-up started (pid 3425)
NetworkManager: <info> Activation (ttyACM0) Stage 4 of 5 (IP4 Configure Get) scheduled...
NetworkManager: <info> Activation (ttyACM0) Stage 4 of 5 (IP4 Configure Get) started...
NetworkManager: <info> Activation (ttyACM0) Stage 5 of 5 (IP Configure Commit) scheduled...
NetworkManager: <info> Activation (ttyACM0) Stage 4 of 5 (IP4 Configure Get) complete.
NetworkManager: <info> Activation (ttyACM0) Stage 5 of 5 (IP Configure Commit) started...
Script /etc/ppp/ip-up finished (pid 3425), status = 0x0
NetworkManager: <info> (ttyACM0): device state change: 7 -> 8 (reason 0)
NetworkManager: <info> Policy set 'surfo2' (ppp0) as default for routing and DNS.
NetworkManager: <info> Activation (ttyACM0) successful, device activated.
NetworkManager: <info> Activation (ttyACM0) Stage 5 of 5 (IP Configure Commit) complete.
NetworkManager: <info> (ttyACM0): device state change: 8 -> 3 (reason 0)
NetworkManager: <info> (ttyACM0): deactivating device (reason: 0).
Terminating on signal 15
** Message: nm-ppp-plugin: (nm_phasechange): status 10 / phase 'terminate'
** Message: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
-- Error received: Numerical result out of range
-- Original message: type=0x19 length=56 flags=<REQUEST,ACK> sequence-nr=1279518316 pid=4197621Connect time 0.4 minutes.
Sent 153 bytes, received 378 bytes.
NetworkManager: <WARN> check_one_route(): (ppp0) error -34 returned from rtnl_route_del(): Sucess
-- Error received: No such process
-- Original message: type=0x19 length=60 flags=<REQUEST,ACK> sequence-nr=1279518317 pid=4197621
NetworkManager: <WARN> check_one_route(): (ppp0) error -3 returned from rtnl_route_del(): Sucess
NetworkManager: <info> (ttyACM0): now unmanaged
NetworkManager: <info> (ttyACM0): device state change: 3 -> 1 (reason 36)
NetworkManager: <info> (ttyACM0): cleaning up...
NetworkManager: <info> (ttyACM0): taking down device.
Script /etc/ppp/ip-down started (pid 3528)
** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
sent [LCP TermReq id=0x3 "User request"]
rcvd [LCP TermAck id=0x3]
** Message: nm-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Connection terminated.
NetworkManager: <info> modem manager disappeared
NetworkManager: <info> Trying to start the modem-manager...
NetworkManager: <info> modem-manager is now available
NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
** Message: nm-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
Script /etc/ppp/ip-down finished (pid 3528), status = 0x0
** Message: nm-ppp-plugin: (nm_exit_notify): cleaning up
NetworkManager: <info> (ttyACM0): new GSM device (driver: 'cdc_acm')
NetworkManager: <info> (ttyACM0): exported as /org/freedesktop/NetworkManager/Devices/3
NetworkManager: <info> (ttyACM0): now managed
NetworkManager: <info> (ttyACM0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (ttyACM0): deactivating device (reason: 2).
NetworkManager: <info> (ttyACM0): device state change: 2 -> 3 (reason 0)
NetworkManager: <debug> [1279518340.999925] ensure_killed(): waiting for ppp pid 3412 to exit
NetworkManager: <debug> [1279518341.000063] ensure_killed(): ppp pid 3412 cleaned up
^C
NetworkManager: <WARN> nm_signal_handler(): Caught signal 2, shutting down normally.
NetworkManager: <info> (eth0): cleaning up...
NetworkManager: <info> (eth0): taking down device.
NetworkManager: <info> (wlan0): taking down device.
NetworkManager: <info> (ttyACM0): now unmanaged
NetworkManager: <info> (ttyACM0): device state change: 3 -> 1 (reason 36)
NetworkManager: <info> (ttyACM0): cleaning up...
NetworkManager: <info> (ttyACM0): taking down device.
(NetworkManager:3317): GLib-GObject-CRITICAL **: g_object_unref: assertion `G_IS_OBJECT (object)' failed
NetworkManager: <info> exiting (success)
ben:~#
