Re: Modem Manager failture

[Dan Williams <dcbw redhat com>]

On Mon, 2011-04-25 at 16:47 +0200, Almo Nito wrote:
> Hallo
> 
>  
> 
> Im trying to figure out why ModemManager fails unlocking/dialing the
> configured gsm connection.

I think this is a variation of something I just hopefully fixed in git
today; basically the modem itself crashes right around dialing time due
to various issues; latest git should hopefully fix this issue, and if
not then I'd really like to hear about it.

Dan

>  
> 
> The connection parameters are:
> 
>  
> 
> bool NetworkManager::addGsmConnection(INTERFACEINFO device,
> 
>                                                QString apn,
> 
>                                                QString
> network_username,
> 
>                                                QString
> network_password,
> 
>                                                QString pin,
> 
>                                                QString dail_number,
> 
>                                                bool home_only)
> 
>     {
> 
>  
> 
>     qDBusRegisterMetaType<Connection>();
> 
>  
> 
>     QDBusInterface dbus_interface("org.freedesktop.NetworkManager",
> "/org/freedesktop/NetworkManagerSettings",
> 
> 
> "org.freedesktop.NetworkManagerSettings",
> QDBusConnection::systemBus());
> 
>  
> 
>     // Create a new connection object
> 
>     Connection connection;
> 
>  
> 
>     // Build up the 'connection' Setting
> 
>     connection["connection"]["uuid"] =
> QUuid::createUuid().toString().remove('{').remove('}');
> 
>     connection["connection"]["id"] = "GSM " + device.Name;
> 
>     connection["connection"]["type"] = QVariant(QString("gsm"));
> 
>     connection["connection"]["autoconnect"] = QVariant(true);
> 
>  
> 
>     // Build up the 'gsm' Setting
> 
>     connection["gsm"];
> 
>  
> 
>     qDebug() << "connecting to:" << apn << network_username <<
> network_password << pin << dail_number<<home_only;
> 
>  
> 
>     connection["gsm"]["apn"]=QVariant(apn);
> 
>     connection["gsm"]["username"]=QVariant(network_username);
> 
>     connection["gsm"]["password"]=QVariant(network_password);
> 
>     connection["gsm"]["pin"]=QVariant(pin);
> 
>     connection["gsm"]["home-only"]=QVariant(home_only);
> 
>     connection["gsm"]["number"]=QVariant(dail_number);
> 
>  
> 
>  
> 
>     // Build up the 'serial' Setting
> 
>     connection["serial"];
> 
>  
> 
>     // Build up the 'ppp' Setting
> 
>     connection["ppp"];
> 
>  
> 
>     // Call AddConnection
> 
>     QDBusMessage result = dbus_interface.call("AddConnection",
> QVariant::fromValue(connection));
> 
>     if (result.type() == QDBusMessage::ErrorMessage) {
> 
>         qDebug() << QString("Error adding connection: %1 %
> 2").arg(result.errorName()).arg(result.errorMessage());
> 
>         return false;
> 
>     } else {
> 
>         qDebug() << QString("Added Connection");
> 
>  
> 
>  
> 
>         QStringList Connections;
> 
>         for(int i=0;i<3;i++) {
> 
>         //wait for the connection to be deployed
> 
>             usleep(NM_SLEEP_FOR_CONNECTIONS/3);
> 
>             Connections=findConnections(device);
> 
>             if(Connections.size()>0) break;
> 
>         }
> 
>  
> 
>         bool valid=true;
> 
>  
> 
>         qDebug() << "Connections matching:" << Connections;
> 
>  
> 
>         foreach(QString conn,Connections) {
> 
>             if(!activateConnection(conn,device))valid=false;
> 
>         }
> 
>  
> 
>         if(Connections.size()<=0) valid=false;
> 
>  
> 
>         return valid;
> 
>     }
> 
>  
> 
>     return false;
> 
> }
> 
>  
> 
>  
> 
>  
> 
>  
> 
> And the output is:
> 
> 
>  
> 
>  
> 
> connecting to: "internet.eplus.de" "eplus" "gprs" "7102" true
> 
> "Added Connection"
> 
> Connections: ("/org/freedesktop/NetworkManagerSettings/0")
> 
> Getting informating for: "/org/freedesktop/NetworkManagerSettings/0"
> 
> Connections: ("/org/freedesktop/NetworkManagerSettings/3",
> "/org/freedesktop/NetworkManagerSettings/0")
> 
> Getting informating for: "/org/freedesktop/NetworkManagerSettings/3"
> 
> found matching gsm connection -> returning true
> 
> Getting informating for: "/org/freedesktop/NetworkManagerSettings/0"
> 
> Connections matching: ("/org/freedesktop/NetworkManagerSettings/3")
> 
> Activating connection: "/org/freedesktop/NetworkManagerSettings/3" on
> device: "/org/freedesktop/NetworkManager/Devices/4"
> 
> Connection activated: true
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: unlock no
> longer required
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: # unlock
> retries for (null) is 0
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state
> changed (disabled -> enabling)
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state
> changed (enabling -> enabled)
> 
> modem-manager: CS registration state changed: 2
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state
> changed (enabled -> searching)
> 
> modem-manager: PS registration state changed: 2
> 
> option: option_instat_callback: error -62
> 
> option: option_instat_callback: error -62
> 
> usb 1-1.2: USB disconnect, address 6
> 
> option: option_instat_callback: error -108
> 
> option1 ttyUSB0: GSM modem (1-port) converter now disconnected from
> ttyUSB0
> 
> option 1-1.2:1.0: device disconnected
> 
> option1 ttyUSB1: GSM modem (1-port) converter now disconnected from
> ttyUSB1
> 
> option 1-1.2:1.1: device disconnected
> 
> option1 ttyUSB2: GSM modem (1-port) converter now disconnected from
> ttyUSB2
> 
> option 1-1.2:1.2: device disconnected
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/3: state
> changed (searching -> disabled)
> 
> modem-manager: Removed
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: mm_callback_info_schedule: assertion `info->pending_id
> == 0' failed
> 
> usb 1-1.2: new full speed USB device using s3c2410-ohci and address 7
> 
> usb 1-1.2: New USB device found, idVendor=12d1, idProduct=1001
> 
> usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=1
> 
> usb 1-1.2: Product: HUAWEI Mobile
> 
> usb 1-1.2: Manufacturer: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
> 
> usb 1-1.2: SerialNumber: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
> 
> usb 1-1.2: configuration #1 chosen from 1 choice
> 
> option 1-1.2:1.0: GSM modem (1-port) converter detected
> 
> usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB0
> 
> option 1-1.2:1.1: GSM modem (1-port) converter detected
> 
> usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB1
> 
> option 1-1.2:1.2: GSM modem (1-port) converter detected
> 
> usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB2
> 
> modem-manager: (Huawei): (ttyUSB1) deferring support check
> 
> modem-manager: (Huawei): (ttyUSB2) deferring support check
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: (ttyUSB0): probe requested by plugin 'Huawei'
> 
> modem-manager: Got failure code 11: SIM PIN required
> 
> modem-manager: Got failure code 11: SIM PIN required
> 
> modem-manager: Got failure code 11: SIM PIN required
> 
> modem-manager: (ttyUSB1): re-checking support...
> 
> modem-manager: (Huawei): (ttyUSB1) deferring support check
> 
> modem-manager: (ttyUSB2): re-checking support...
> 
> modem-manager: (Huawei): (ttyUSB2) deferring support check
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: (Huawei): GSM
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2 claimed port
> ttyUSB0
> 
> modem-manager: Added
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: (tty/ttyUSB0): outstanding support task prevents export
> of /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: (tty/ttyUSB1): outstanding support task prevents export
> of /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: get_unlock_retries: pin type 'sim-pin'
> 
> modem-manager: (tty/ttyUSB1): outstanding support task prevents export
> of /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: (ttyUSB1): re-checking support...
> 
> modem-manager: (ttyUSB1) opening serial device...
> 
> modem-manager: (ttyUSB2): re-checking support...
> 
> modem-manager: (ttyUSB2) opening serial device...
> 
> modem-manager: (ttyUSB1) closing serial device...
> 
> modem-manager: (ttyUSB2) closing serial device...
> 
> modem-manager: (Huawei): GSM
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2 claimed port
> ttyUSB1
> 
> modem-manager: (tty/ttyUSB2): outstanding support task prevents export
> of /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: (Huawei): GSM
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2 claimed port
> ttyUSB2
> 
> modem-manager: Exported
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> as /org/freedesktop/ModemManager/Modems/4
> 
> modem-manager: (/org/freedesktop/ModemManager/Modems/4): data port is
> ttyUSB0
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/4: unlock no
> longer required
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/4: # unlock
> retries for (null) is 0
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/4: state
> changed (disabled -> enabling)
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/4: state
> changed (enabling -> enabled)
> 
>  
> 
> root@PowerDog:~#
> 
> root@PowerDog:~# clear
> 
> root@PowerDog:~# ---------------------------------------
> 
> Gathering Data for: "/org/freedesktop/NetworkManager/Devices/0"
> 
> Active state
> 
> Here
> 
> gathering IP info of: "org.freedesktop.NetworkManager.IP4Config"
> "/org/freedesktop/NetworkManager/IP4Config/0"
> 
> Device Specific:
> 
> --------------------
> 
> Wired Settings:
> 
> --------------------
> 
> Plugged In true
> 
> Speed 100
> 
> HwAddress "08:08:11:FF:FF:FF"
> 
> --------------------
> 
> Udi "/sys/devices/platform/dm9000/net/eth0"
> 
> Interface "eth0"
> 
> Driver "dm9000"
> 
> Capabilities 3
> 
> State 8
> 
> Managed true
> 
> FirmwareMissing false
> 
> DeviceType 1
> 
> uses_DHCP false
> 
> uses_IPv6 false
> 
> IPObject "/org/freedesktop/NetworkManager/IP4Config/0"
> 
> IP-Address  QHostAddress( "192.168.1.50" )
> 
> Netmask  QHostAddress( "255.255.255.0" )
> 
> Gateway  QHostAddress( "192.168.1.1" )
> 
> Nameservers:  QHostAddress( "" )   QHostAddress( "" )
> 
> ---------------------------------------
> 
> Gathering Data for: "/org/freedesktop/NetworkManager/Devices/5"
> 
> Device Specific:
> 
> Udi "/org/freedesktop/ModemManager/Modems/4"
> 
> Interface "ttyUSB0"
> 
> Driver "option1"
> 
> Capabilities 1
> 
> State 3
> 
> Managed true
> 
> FirmwareMissing false
> 
> DeviceType 3
> 
> uses_DHCP false
> 
> uses_IPv6 false
> 
> IPObject ""
> 
> IP-Address  QHostAddress( "" )
> 
> Netmask  QHostAddress( "" )
> 
> Gateway  QHostAddress( "" )
> 
> Nameservers:  QHostAddress( "" )   QHostAddress( "" )
> 
> FOUND LAN INTERFACE: "eth0"
> 
> FOUND GSM MODEM: "ttyUSB0"
> 
> Dailing gsm connection on "ttyUSB0"
> 
> Connections: ("/org/freedesktop/NetworkManagerSettings/3",
> "/org/freedesktop/NetworkManagerSettings/0")
> 
> Getting informating for: "/org/freedesktop/NetworkManagerSettings/3"
> 
> found matching gsm connection -> returning true
> 
> Getting informating for: "/org/freedesktop/NetworkManagerSettings/0"
> 
> Deleting "/org/freedesktop/NetworkManagerSettings/3"
> 
> Deleting Connection "/org/freedesktop/NetworkManagerSettings/3"
> 
> connecting to: "internet.eplus.de" "eplus" "gprs" "7102" true
> 
> "Added Connection"
> 
> Connections: ("/org/freedesktop/NetworkManagerSettings/0")
> 
> Getting informating for: "/org/freedesktop/NetworkManagerSettings/0"
> 
> Connections: ("/org/freedesktop/NetworkManagerSettings/4",
> "/org/freedesktop/NetworkManagerSettings/0")
> 
> Getting informating for: "/org/freedesktop/NetworkManagerSettings/4"
> 
> option: option_instat_callback: error -62
> 
> found matching gsm connection -> returning true
> 
> Getting informating for: "/org/freedesktop/NetworkManagerSettings/0"
> 
> Connections matching: ("/org/freedesktop/NetworkManagerSettings/4")
> 
> Activating connection: "/org/freedesktop/NetworkManagerSettings/4" on
> device: "/org/freedesktop/NetworkManager/Devices/5"
> 
> option: option_instat_callback: error -62
> 
> Connection activated: true
> 
> option: option_instat_callback: error -62
> 
> usb 1-1.2: USB disconnect, address 7
> 
> option: option_instat_callback: error -108
> 
> option1 ttyUSB0: GSM modem (1-port) converter now disconnected from
> ttyUSB0
> 
> option 1-1.2:1.0: device disconnected
> 
> option1 ttyUSB1: GSM modem (1-port) converter now disconnected from
> ttyUSB1
> 
> option 1-1.2:1.1: device disconnected
> 
> option1 ttyUSB2: GSM modem (1-port) converter now disconnected from
> ttyUSB2
> 
> option 1-1.2:1.2: device disconnected
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/4: state
> changed (enabled -> disabled)
> 
> modem-manager: Removed
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: mm_callback_info_schedule: assertion `info->pending_id
> == 0' failed
> 
> usb 1-1.2: new full speed USB device using s3c2410-ohci and address 8
> 
> usb 1-1.2: New USB device found, idVendor=12d1, idProduct=1001
> 
> usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=1
> 
> usb 1-1.2: Product: HUAWEI Mobile
> 
> usb 1-1.2: Manufacturer: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
> 
> usb 1-1.2: SerialNumber: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
> 
> usb 1-1.2: configuration #1 chosen from 1 choice
> 
> option 1-1.2:1.0: GSM modem (1-port) converter detected
> 
> usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB0
> 
> option 1-1.2:1.1: GSM modem (1-port) converter detected
> 
> usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB1
> 
> option 1-1.2:1.2: GSM modem (1-port) converter detected
> 
> usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB2
> 
> modem-manager: (Huawei): (ttyUSB2) deferring support check
> 
> modem-manager: (Huawei): (ttyUSB1) deferring support check
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: (ttyUSB0): probe requested by plugin 'Huawei'
> 
> modem-manager: Got failure code 11: SIM PIN required
> 
> modem-manager: Got failure code 11: SIM PIN required
> 
> modem-manager: Got failure code 11: SIM PIN required
> 
> modem-manager: (ttyUSB2): re-checking support...
> 
> modem-manager: (Huawei): (ttyUSB2) deferring support check
> 
> modem-manager: (ttyUSB1): re-checking support...
> 
> modem-manager: (Huawei): (ttyUSB1) deferring support check
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: (Huawei): GSM
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2 claimed port
> ttyUSB0
> 
> modem-manager: Added
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: (tty/ttyUSB0): outstanding support task prevents export
> of /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: (tty/ttyUSB1): outstanding support task prevents export
> of /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: get_unlock_retries: pin type 'sim-pin'
> 
> modem-manager: (tty/ttyUSB1): outstanding support task prevents export
> of /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: (ttyUSB2): re-checking support...
> 
> modem-manager: (ttyUSB2) opening serial device...
> 
> modem-manager: (ttyUSB1): re-checking support...
> 
> modem-manager: (ttyUSB1) opening serial device...
> 
> modem-manager: (ttyUSB2) closing serial device...
> 
> modem-manager: (ttyUSB1) closing serial device...
> 
> modem-manager: (Huawei): GSM
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2 claimed port
> ttyUSB2
> 
> modem-manager: (tty/ttyUSB1): outstanding support task prevents export
> of /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> 
> modem-manager: (Huawei): GSM
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2 claimed port
> ttyUSB1
> 
> modem-manager: Exported
> modem /sys/devices/platform/s3c2410-ohci/usb1/1-1/1-1.2
> as /org/freedesktop/ModemManager/Modems/5
> 
> modem-manager: (/org/freedesktop/ModemManager/Modems/5): data port is
> ttyUSB0
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/5: unlock no
> longer required
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/5: # unlock
> retries for (null) is 0
> 
> modem-manager: (ttyUSB0) closing serial device...
> 
> modem-manager: (ttyUSB0) opening serial device...
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/5: state
> changed (disabled -> enabling)
> 
> modem-manager: Modem /org/freedesktop/ModemManager/Modems/5: state
> changed (enabling -> enabled)
> 
>  
> 
>  
> 
> Please can anybody see the issue?
> 
> Kindest regards
> 
>  
> 
> 
> _______________________________________________
> networkmanager-list mailing list
> networkmanager-list gnome org
> http://mail.gnome.org/mailman/listinfo/networkmanager-list