2010-09-07 08:18:50+0100 [-] Log opened. 2010-09-07 08:18:50+0100 [-] twistd 10.1.0 (/usr/bin/python 2.6.6) starting up. 2010-09-07 08:18:50+0100 [-] reactor class: twisted.internet.glib2reactor.Glib2Reactor. 2010-09-07 08:19:21+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1 action: add 2010-09-07 08:19:21+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.0 action: add 2010-09-07 08:19:21+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.0 action: remove 2010-09-07 08:19:21+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1 action: remove 2010-09-07 08:19:22+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1 action: add 2010-09-07 08:19:22+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.2 action: add 2010-09-07 08:19:22+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.3 action: add 2010-09-07 08:19:22+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.1 action: add 2010-09-07 08:19:22+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.0 action: add 2010-09-07 08:19:22+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.1/ttyUSB1/tty/ttyUSB1 action: add 2010-09-07 08:19:22+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.0/ttyUSB0/tty/ttyUSB0 action: add 2010-09-07 08:19:22+0100 [-] UEVENT device: /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.2/ttyUSB2/tty/ttyUSB2 action: add 2010-09-07 08:19:28+0100 [-] get_plugin_by_id called with 0x12D1 and 0x1001 2010-09-07 08:19:28+0100 [-] /dev/ttyUSB0: ID_MM_PORT_TYPE_MODEM 2010-09-07 08:19:28+0100 [-] /dev/ttyUSB2: ID_MM_PORT_TYPE_AUX 2010-09-07 08:19:30+0100 [-] at+cgmm response: ['K3520'] 2010-09-07 08:19:30+0100 [-] registering plugin with opath /org/freedesktop/ModemManager/Devices/0 2010-09-07 08:19:30+0100 [-] wrapping plugin with class 2010-09-07 08:19:30+0100 [-] exporting methods with class 2010-09-07 08:19:30+0100 [-] emitting DeviceAdded('/org/freedesktop/ModemManager/Devices/0') ############################### nm attempt ########################## 2010-09-07 08:19:36+0100 [] idle: sending 'AT+CPIN="0000"' 2010-09-07 08:19:36+0100 [] state change: idle -> waiting 2010-09-07 08:19:36+0100 [-] Unhandled error in Deferred: 2010-09-07 08:19:36+0100 [-] Unhandled Error Traceback (most recent call last): File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 441, in _runCallbacks self.result = callback(self.result, *args, **kw) File "/usr/local/lib/python2.6/dist-packages/wader/common/protocol.py", line 396, in _process_at_cmd d.addCallback(_transition_and_send) File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 260, in addCallback callbackKeywords=kw) File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 249, in addCallbacks self._runCallbacks() --- --- File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 441, in _runCallbacks self.result = callback(self.result, *args, **kw) File "/usr/local/lib/python2.6/dist-packages/wader/common/protocol.py", line 393, in _transition_and_send self.transport.write(cmd.get_cmd()) exceptions.AttributeError: 'NoneType' object has no attribute 'write' 2010-09-07 08:19:51+0100 [] state change: waiting -> idle ######################### bcm attempt ######################### 2010-09-07 08:20:19+0100 [-] starting AuthStateMachine ... 2010-09-07 08:20:19+0100 [-] authentication_sm: transition to get_pin_status mode.... 2010-09-07 08:20:19+0100 [ttyUSB2] idle: sending 'AT+CPIN?' 2010-09-07 08:20:19+0100 [ttyUSB2] state change: idle -> waiting 2010-09-07 08:20:19+0100 [ttyUSB2] waiting: callback = [('SIM PIN',)] 2010-09-07 08:20:19+0100 [ttyUSB2] authentication_sm: notifying auth failure org.freedesktop.ModemManager.Gsm.SimPinRequired: 2010-09-07 08:20:20+0100 [ttyUSB2] state change: waiting -> idle 2010-09-07 08:20:23+0100 [ttyUSB2] idle: sending 'AT+CPIN="0000"' 2010-09-07 08:20:23+0100 [ttyUSB2] state change: idle -> waiting 2010-09-07 08:20:23+0100 [ttyUSB2] waiting: callback = [('OK',)] 2010-09-07 08:20:23+0100 [ttyUSB2] emitting MmPropertiesChanged: org.freedesktop.ModemManager.Modem {'UnlockRequired': ''} 2010-09-07 08:20:23+0100 [ttyUSB2] giving the device 15 seconds to settle, waiting... 2010-09-07 08:20:23+0100 [ttyUSB2] state change: waiting -> idle 2010-09-07 08:20:27+0100 [ttyUSB2] idle: '\r\n^RSSI:1\r\n' 2010-09-07 08:20:27+0100 [ttyUSB2] emitting SignalQuality(3) 2010-09-07 08:20:29+0100 [ttyUSB2] idle: '\r\n^MODE:5,4\r\n' 2010-09-07 08:20:29+0100 [ttyUSB2] emitting NetworkMode(8) 2010-09-07 08:20:29+0100 [ttyUSB2] emitting MmPropertiesChanged: org.freedesktop.ModemManager.Modem.Gsm.Network {'AccessTechnology': 5} 2010-09-07 08:20:29+0100 [ttyUSB2] idle: '\r\n^SIMST:1\r\n\r\n^SRVST:2\r\n' 2010-09-07 08:20:29+0100 [ttyUSB2] idle: '\r\n^MODE:3,2\r\n' 2010-09-07 08:20:29+0100 [ttyUSB2] emitting NetworkMode(2) 2010-09-07 08:20:29+0100 [ttyUSB2] emitting MmPropertiesChanged: org.freedesktop.ModemManager.Modem.Gsm.Network {'AccessTechnology': 3} 2010-09-07 08:20:29+0100 [ttyUSB2] idle: '\r\n^BOOT:20012905,0,0,0,72\r\n' 2010-09-07 08:20:31+0100 [ttyUSB2] idle: '\r\n^MODE:3,2\r\n' 2010-09-07 08:20:31+0100 [ttyUSB2] emitting NetworkMode(2) 2010-09-07 08:20:31+0100 [ttyUSB2] emitting MmPropertiesChanged: org.freedesktop.ModemManager.Modem.Gsm.Network {'AccessTechnology': 3} 2010-09-07 08:20:31+0100 [ttyUSB2] idle: '\r\n^RSSI:17\r\n' 2010-09-07 08:20:31+0100 [ttyUSB2] emitting SignalQuality(54) 2010-09-07 08:20:38+0100 [-] emitting MmPropertiesChanged: org.freedesktop.ModemManager.Modem {'Enabled': False} 2010-09-07 08:20:38+0100 [ttyUSB2] idle: sending 'AT+CFUN?' 2010-09-07 08:20:38+0100 [ttyUSB2] state change: idle -> waiting 2010-09-07 08:20:38+0100 [ttyUSB2] waiting: callback = [('1',)] 2010-09-07 08:20:38+0100 [ttyUSB2] state change: waiting -> idle 2010-09-07 08:20:38+0100 [ttyUSB2] idle: sending 'AT+CMEE=1' 2010-09-07 08:20:38+0100 [ttyUSB2] state change: idle -> waiting 2010-09-07 08:20:38+0100 [ttyUSB2] waiting: callback = [('OK',)] 2010-09-07 08:20:38+0100 [ttyUSB2] state change: waiting -> idle 2010-09-07 08:20:38+0100 [ttyUSB2] idle: sending 'AT+CSCS=?' 2010-09-07 08:20:38+0100 [ttyUSB2] state change: idle -> waiting 2010-09-07 08:20:38+0100 [ttyUSB2] waiting: callback = [('IRA',), ('GSM',), ('UCS2',)]