cold boot
enter pin to zhone:

ginger:~# frameworkd 
frameworkd INFO     Using configuration file <framework.configparse.SmartConfigParser instance at 0x402a5f58>
frameworkd.controller INFO     setting logger for oeventsd to 20
frameworkd.controller INFO     setting logger for odeviced.powercontrol-neo to 20
frameworkd.controller INFO     setting logger for frameworkd to 10
frameworkd.controller INFO     setting logger for ogpsd to 20
frameworkd.controller INFO     setting logger for opreferencesd to 20
frameworkd.controller INFO     setting logger for odeviced.kernel26 to 20
frameworkd.controller INFO     setting logger for odeviced to 20
frameworkd.controller INFO     setting logger for odeviced.idlenotifier to 20
frameworkd.controller INFO     setting logger for odeviced.input to 20
frameworkd.controller INFO     setting logger for ogsmd to 20
frameworkd.subsystem DEBUG    subsystem frameworkd created
frameworkd.subsystem DEBUG    ...in subsystem frameworkd: found module <module 'framework.objectquery' from '/var/lib/python-support/python2.5/framework/objectquery.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.objectquery' from '/var/lib/python-support/python2.5/framework/objectquery.pyc'> doesn't need additional busnames
frameworkd.subsystem DEBUG    service frameworkd now owning busnames [<dbus.service.BusName org.freesmartphone.frameworkd on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x2b0650>]
frameworkd.subsystem DEBUG    subsystem opreferencesd created
frameworkd.subsystem DEBUG    ...in subsystem opreferencesd: found module <module 'framework.subsystems.opreferencesd.configuration' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/configuration.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.opreferencesd.configuration' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/configuration.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem opreferencesd: found module <module 'framework.subsystems.opreferencesd.opreferences' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/opreferences.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.opreferencesd.opreferences' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/opreferences.pyc'> doesn't need additional busnames
opreferencesd INFO     initialized, services : ['profiles']
frameworkd.subsystem DEBUG    ...in subsystem opreferencesd: found module <module 'framework.subsystems.opreferencesd.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/helpers.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.opreferencesd.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/helpers.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem opreferencesd: found module <module 'framework.subsystems.opreferencesd.service' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/service.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.opreferencesd.service' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/service.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem opreferencesd: found module <module 'framework.subsystems.opreferencesd.schema' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/schema.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.opreferencesd.schema' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/schema.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem opreferencesd: found module <module 'framework.subsystems.opreferencesd.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/__init__.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.opreferencesd.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/opreferencesd/__init__.pyc'>
frameworkd.subsystem DEBUG    service opreferencesd now owning busnames [<dbus.service.BusName org.freesmartphone.opreferencesd on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x2c8d30>]
frameworkd.subsystem DEBUG    subsystem ogpsd created
frameworkd.subsystem DEBUG    ...in subsystem ogpsd: found module <module 'framework.subsystems.ogpsd.gpschannel' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/gpschannel.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ogpsd.gpschannel' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/gpschannel.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ogpsd: found module <module 'framework.subsystems.ogpsd.om' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/om.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ogpsd.om' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/om.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ogpsd: found module <module 'framework.subsystems.ogpsd.gpsdevice' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/gpsdevice.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ogpsd.gpsdevice' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/gpsdevice.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ogpsd: found module <module 'framework.subsystems.ogpsd.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/__init__.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ogpsd.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/__init__.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ogpsd: found module <module 'framework.subsystems.ogpsd.ubx' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/ubx.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ogpsd.ubx' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/ubx.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ogpsd: found module <module 'framework.subsystems.ogpsd.nmea' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/nmea.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ogpsd.nmea' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/nmea.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ogpsd: found module <module 'framework.subsystems.ogpsd.factory' from '/var/lib/python-support/python2.5/framework/subsystems/ogpsd/factory.pyc'>
ogpsd    INFO     GTA02Device initialized. Serving org.freedesktop.Gypsy at /org/freedesktop/Gypsy
frameworkd.subsystem DEBUG    service ogpsd now owning busnames [<dbus.service.BusName org.freesmartphone.ogpsd on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x330830>, <dbus.service.BusName org.freedesktop.Gypsy on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x44a730>]
frameworkd.subsystem DEBUG    subsystem odeviced created
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.accelerometer' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/accelerometer.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.odeviced.accelerometer' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/accelerometer.pyc'> doesn't need additional busnames
odeviced.accelerometer INFO     FSOSubsystem 0.2.0 initialized. Serving org.freesmartphone.Device.Accelerometer at /org/freesmartphone/Device/Accelerometer
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.audio' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/audio.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.odeviced.audio' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/audio.pyc'> doesn't need additional busnames
odeviced.audio INFO     Audio 0.3.0 initialized. Serving org.freesmartphone.Device.Audio at /org/freesmartphone/Device/Audio
init
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.input' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/input.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.odeviced.input' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/input.pyc'> doesn't need additional busnames
init
odeviced.input INFO     Input 0.9.9 initialized. Serving org.freesmartphone.Device.Input at /org/freesmartphone/Device/Input
odeviced.input INFO     skipping input node 2 due to configuration
odeviced.input INFO     skipping input node 3 due to configuration
odeviced.input INFO     opened 3 input file descriptors
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.idlenotifier' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/idlenotifier.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.odeviced.idlenotifier' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/idlenotifier.pyc'> doesn't need additional busnames
odeviced.idlenotifier INFO     IdleNotifier 0.9.9 initialized. Serving org.freesmartphone.Device.IdleNotifier at /org/freesmartphone/Device/IdleNotifier/0
odeviced.idlenotifier INFO     skipping input node 2 due to configuration
odeviced.idlenotifier INFO     skipping input node 3 due to configuration
odeviced.idlenotifier INFO     skipping input node 4 due to configuration
odeviced.idlenotifier INFO     opened 2 input file descriptors
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/helpers.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.odeviced.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/helpers.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.info' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/info.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.odeviced.info' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/info.pyc'> doesn't need additional busnames
odeviced.info INFO     Info 0.1.2 initialized. Serving org.freesmartphone.Device.Info at /org/freesmartphone/Device/Info
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.kernel26' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/kernel26.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.odeviced.kernel26' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/kernel26.pyc'> doesn't need additional busnames
odeviced.kernel26 INFO     Display 0.9.2 initialized. Serving org.freesmartphone.Device.Display at /org/freesmartphone/Device/Display/pcf50633_bl
odeviced.kernel26 INFO     LED 0.9.2 initialized. Serving org.freesmartphone.Device.LED at /org/freesmartphone/Device/LED/neo1973_vibrator
odeviced.kernel26 INFO     LED 0.9.2 initialized. Serving org.freesmartphone.Device.LED at /org/freesmartphone/Device/LED/gta02_power_orange
odeviced.kernel26 INFO     LED 0.9.2 initialized. Serving org.freesmartphone.Device.LED at /org/freesmartphone/Device/LED/gta02_power_blue
odeviced.kernel26 INFO     LED 0.9.2 initialized. Serving org.freesmartphone.Device.LED at /org/freesmartphone/Device/LED/gta02_aux_red
odeviced.kernel26 INFO     PowerSupplyApm 0.9.2 initialized. Serving org.freesmartphone.Device.PowerSupply at /org/freesmartphone/Device/PowerSupply/apm
odeviced.kernel26 INFO     PowerSupply 0.9.2 initialized. Serving org.freesmartphone.Device.PowerSupply at /org/freesmartphone/Device/PowerSupply/bat
odeviced.kernel26 INFO     RealTimeClock 0.9.2 initialized. Serving org.freesmartphone.Device.RealTimeClock at /org/freesmartphone/Device/RealTimeClock/rtc0
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.powercontrol-neo' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/powercontrol-neo.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.odeviced.powercontrol-neo' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/powercontrol-neo.pyc'> doesn't need additional busnames
odeviced.powercontrol-neo INFO     NeoGsmPowerControl 0.5.1 initialized. Serving org.freesmartphone.Device.PowerControl at /org/freesmartphone/Device/PowerControl/GSM
odeviced.powercontrol-neo INFO     NeoBluetoothPowerControl 0.5.1 initialized. Serving org.freesmartphone.Device.PowerControl at /org/freesmartphone/Device/PowerControl/Bluetooth
odeviced.powercontrol-neo INFO     NeoGpsPowerControl 0.5.1 initialized. Serving org.freesmartphone.Device.PowerControl at /org/freesmartphone/Device/PowerControl/GPS
odeviced.powercontrol-neo INFO     NeoWifiPowerControl 0.5.1 initialized. Serving org.freesmartphone.Device.PowerControl at /org/freesmartphone/Device/PowerControl/WiFi
frameworkd.subsystem DEBUG    ...in subsystem odeviced: found module <module 'framework.subsystems.odeviced.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/__init__.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.odeviced.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/odeviced/__init__.pyc'>
frameworkd.subsystem DEBUG    service odeviced now owning busnames [<dbus.service.BusName org.freesmartphone.odeviced on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x418350>]
frameworkd.subsystem DEBUG    subsystem ogsmd created
frameworkd.subsystem DEBUG    ...in subsystem ogsmd: found module <module 'framework.subsystems.ogsmd.objects' from '/var/lib/python-support/python2.5/framework/subsystems/ogsmd/objects.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.ogsmd.objects' from '/var/lib/python-support/python2.5/framework/subsystems/ogsmd/objects.pyc'> doesn't need additional busnames
ogsmd    INFO     Device initialized. Serving org.freesmartphone.GSM.Device at /org/freesmartphone/GSM/Device
ogsmd    INFO     (<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0>: Creating channel with timeout = 3600 seconds)
ogsmd    INFO     (<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0>: Creating channel with timeout = 5 seconds)
ogsmd    INFO     (<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930>: Creating channel with timeout = 5 seconds)
ogsmd    INFO     Server initialized. Serving org.freesmartphone.GSM.Server at /org/freesmartphone/GSM/Server
frameworkd.subsystem DEBUG    ...in subsystem ogsmd: found module <module 'framework.subsystems.ogsmd.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/ogsmd/helpers.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ogsmd.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/ogsmd/helpers.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ogsmd: found module <module 'framework.subsystems.ogsmd.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/ogsmd/__init__.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ogsmd.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/ogsmd/__init__.pyc'>
frameworkd.subsystem DEBUG    service ogsmd now owning busnames [<dbus.service.BusName org.freesmartphone.ogsmd on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x418290>]
frameworkd.subsystem DEBUG    subsystem ophoned created
frameworkd.subsystem DEBUG    ...in subsystem ophoned: found module <module 'framework.subsystems.ophoned.protocol' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/protocol.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ophoned.protocol' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/protocol.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ophoned: found module <module 'framework.subsystems.ophoned.gsm' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/gsm.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ophoned.gsm' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/gsm.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ophoned: found module <module 'framework.subsystems.ophoned.test' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/test.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ophoned.test' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/test.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ophoned: found module <module 'framework.subsystems.ophoned.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/__init__.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ophoned.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/__init__.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ophoned: found module <module 'framework.subsystems.ophoned.ophoned' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/ophoned.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.ophoned.ophoned' from '/var/lib/python-support/python2.5/framework/subsystems/ophoned/ophoned.pyc'> doesn't need additional busnames
ophoned  INFO     Logging test
frameworkd.subsystem DEBUG    service ophoned now owning busnames [<dbus.service.BusName org.freesmartphone.ophoned on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x418270>]
frameworkd.subsystem DEBUG    subsystem oeventd created
frameworkd.subsystem DEBUG    ...in subsystem oeventd: found module <module 'framework.subsystems.oeventd.receiver' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/receiver.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.oeventd.receiver' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/receiver.pyc'> doesn't need additional busnames
oeventd  INFO     Receiver initialized. Serving org.freesmartphone.Event.Receiver at [(<dbus._dbus.SystemBus (system) at 0x40283990>, '/org/freesmartphone/Event/Receiver/0', False)]
oeventd  INFO     AudioSetupReceiver initialized. Serving org.freesmartphone.Event.Receiver at [(<dbus._dbus.SystemBus (system) at 0x40283990>, '/org/freesmartphone/Event/Receiver/1', False)]
oeventd  INFO     VibratorReceiver initialized. Serving org.freesmartphone.Event.Receiver at [(<dbus._dbus.SystemBus (system) at 0x40283990>, '/org/freesmartphone/Event/Receiver/2', False)]
oeventd  INFO     RingReceiver initialized. Serving org.freesmartphone.Event.Receiver at [(<dbus._dbus.SystemBus (system) at 0x40283990>, '/org/freesmartphone/Event/Receiver/3', False)]
frameworkd.subsystem DEBUG    ...in subsystem oeventd: found module <module 'framework.subsystems.oeventd.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/helpers.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.oeventd.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/helpers.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem oeventd: found module <module 'framework.subsystems.oeventd.manager' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/manager.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.oeventd.manager' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/manager.pyc'> doesn't need additional busnames
oeventd  INFO     Manager initialized. Serving org.freesmartphone.Event at [(<dbus._dbus.SystemBus (system) at 0x40283990>, '/org/freesmartphone/Event', False)]
frameworkd.subsystem DEBUG    ...in subsystem oeventd: found module <module 'framework.subsystems.oeventd.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/__init__.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.oeventd.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/__init__.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem oeventd: found module <module 'framework.subsystems.oeventd.trigger' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/trigger.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.oeventd.trigger' from '/var/lib/python-support/python2.5/framework/subsystems/oeventd/trigger.pyc'> doesn't need additional busnames
oeventd  INFO     CallTrigger initialized. Serving org.freesmartphone.Event.Trigger at [(<dbus._dbus.SystemBus (system) at 0x40283990>, '/org/freesmartphone/Event/Trigger/0', False)]
frameworkd.subsystem DEBUG    service oeventd now owning busnames [<dbus.service.BusName org.freesmartphone.oeventd on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x418410>]
frameworkd.subsystem DEBUG    subsystem ousaged created
frameworkd.subsystem DEBUG    ...in subsystem ousaged: found module <module 'framework.subsystems.ousaged.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/ousaged/helpers.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ousaged.helpers' from '/var/lib/python-support/python2.5/framework/subsystems/ousaged/helpers.pyc'>
frameworkd.subsystem DEBUG    ...in subsystem ousaged: found module <module 'framework.subsystems.ousaged.generic' from '/var/lib/python-support/python2.5/framework/subsystems/ousaged/generic.pyc'>
frameworkd.subsystem DEBUG    module <module 'framework.subsystems.ousaged.generic' from '/var/lib/python-support/python2.5/framework/subsystems/ousaged/generic.pyc'> doesn't need additional busnames
ousaged  INFO     GenericUsageControl initialized. Serving org.freesmartphone.Usage at /org/freesmartphone/Usage
frameworkd.subsystem DEBUG    ...in subsystem ousaged: found module <module 'framework.subsystems.ousaged.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/ousaged/__init__.pyc'>
frameworkd.subsystem DEBUG    no plugin: factory function not found in module <module 'framework.subsystems.ousaged.__init__' from '/var/lib/python-support/python2.5/framework/subsystems/ousaged/__init__.pyc'>
frameworkd.subsystem DEBUG    service ousaged now owning busnames [<dbus.service.BusName org.freesmartphone.ousaged on <dbus._dbus.SystemBus (system) at 0x40283990> at 0x5d7cd0>]
frameworkd.controller INFO     ================== objects registered ===================
frameworkd.controller INFO     /org/freedesktop/Gypsy [org.freedesktop.Gypsy]
frameworkd.controller INFO     /org/freesmartphone/Device/Accelerometer [org.freesmartphone.Device.Accelerometer]
frameworkd.controller INFO     /org/freesmartphone/Device/Audio [org.freesmartphone.Device.Audio]
frameworkd.controller INFO     /org/freesmartphone/Device/Display/pcf50633_bl [org.freesmartphone.Device.Display]
frameworkd.controller INFO     /org/freesmartphone/Device/IdleNotifier/0 [org.freesmartphone.Device.IdleNotifier]
frameworkd.controller INFO     /org/freesmartphone/Device/Info [org.freesmartphone.Device.Info]
frameworkd.controller INFO     /org/freesmartphone/Device/Input [org.freesmartphone.Device.Input]
frameworkd.controller INFO     /org/freesmartphone/Device/LED/gta02_aux_red [org.freesmartphone.Device.LED]
frameworkd.controller INFO     /org/freesmartphone/Device/LED/gta02_power_blue [org.freesmartphone.Device.LED]
frameworkd.controller INFO     /org/freesmartphone/Device/LED/gta02_power_orange [org.freesmartphone.Device.LED]
frameworkd.controller INFO     /org/freesmartphone/Device/LED/neo1973_vibrator [org.freesmartphone.Device.LED]
frameworkd.controller INFO     /org/freesmartphone/Device/PowerControl/Bluetooth [org.freesmartphone.Device.PowerControl]
frameworkd.controller INFO     /org/freesmartphone/Device/PowerControl/GPS [org.freesmartphone.Device.PowerControl]
frameworkd.controller INFO     /org/freesmartphone/Device/PowerControl/GSM [org.freesmartphone.Device.PowerControl]
frameworkd.controller INFO     /org/freesmartphone/Device/PowerControl/WiFi [org.freesmartphone.Device.PowerControl]
frameworkd.controller INFO     /org/freesmartphone/Device/PowerSupply/apm [org.freesmartphone.Device.PowerSupply]
frameworkd.controller INFO     /org/freesmartphone/Device/PowerSupply/bat [org.freesmartphone.Device.PowerSupply]
frameworkd.controller INFO     /org/freesmartphone/Device/RealTimeClock/rtc0 [org.freesmartphone.Device.RealTimeClock]
frameworkd.controller INFO     /org/freesmartphone/Event [org.freesmartphone.Event]
frameworkd.controller INFO     /org/freesmartphone/Event/Receiver/0 [org.freesmartphone.Event.Receiver]
frameworkd.controller INFO     /org/freesmartphone/Event/Receiver/1 [org.freesmartphone.Event.Receiver]
frameworkd.controller INFO     /org/freesmartphone/Event/Receiver/2 [org.freesmartphone.Event.Receiver]
frameworkd.controller INFO     /org/freesmartphone/Event/Receiver/3 [org.freesmartphone.Event.Receiver]
frameworkd.controller INFO     /org/freesmartphone/Event/Trigger/0 [org.freesmartphone.Event.Trigger]
frameworkd.controller INFO     /org/freesmartphone/Framework [org.freesmartphone.Objects]
frameworkd.controller INFO     /org/freesmartphone/GSM/Device [org.freesmartphone.GSM.Device]
frameworkd.controller INFO     /org/freesmartphone/GSM/Server [org.freesmartphone.GSM.Server]
frameworkd.controller INFO     /org/freesmartphone/Phone [org.freesmartphone.Phone]
frameworkd.controller INFO     /org/freesmartphone/Preferences [org.freesmartphone.Preferences]
frameworkd.controller INFO     /org/freesmartphone/Usage [org.freesmartphone.Usage]
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to IDLE
frameworkd.controller DEBUG    entered mainloop

ogsmd    INFO     (<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930>: using modem path '/dev/pts/3')
ogsmd    INFO     (modem responding)
ogsmd    INFO     (<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0>: using modem path '/dev/pts/4')
ogsmd    INFO     (modem responding)
ogsmd    INFO     (<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0>: using modem path '/dev/pts/5')
ogsmd    INFO     (modem responding)
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('ATZ\r\n', None, None, 5), ('ATE0V1\r\n', None, None, 5), ('AT+CMEE=1\r\n', None, None, 5), ('AT+CRC=1\r\n', None, None, 5), ('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('ATZ\r\n', None, None, 5), ('ATE0V1\r\n', None, None, 5), ('AT+CMEE=1\r\n', None, None, 5), ('AT+CRC=1\r\n', None, None, 5), ('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('ATE0V1\r\n', None, None, 3600), ('AT+CMEE=1\r\n', None, None, 3600), ('AT+CRC=1\r\n', None, None, 3600), ('AT+CSCS="8859-1"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=1\r\n', None, None, 3600), ('AT+CSMS=1\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('ATE0V1\r\n', None, None, 5), ('AT+CMEE=1\r\n', None, None, 5), ('AT+CRC=1\r\n', None, None, 5), ('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('ATE0V1\r\n', None, None, 5), ('AT+CMEE=1\r\n', None, None, 5), ('AT+CRC=1\r\n', None, None, 5), ('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CMEE=1\r\n', None, None, 5), ('AT+CRC=1\r\n', None, None, 5), ('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CMEE=1\r\n', None, None, 3600), ('AT+CRC=1\r\n', None, None, 3600), ('AT+CSCS="8859-1"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=1\r\n', None, None, 3600), ('AT+CSMS=1\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CMEE=1\r\n', None, None, 5), ('AT+CRC=1\r\n', None, None, 5), ('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CRC=1\r\n', None, None, 5), ('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CRC=1\r\n', None, None, 3600), ('AT+CSCS="8859-1"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=1\r\n', None, None, 3600), ('AT+CSMS=1\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CRC=1\r\n', None, None, 5), ('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CSCS="8859-1"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=1\r\n', None, None, 3600), ('AT+CSMS=1\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CSCS="8859-1"\r\n', None, None, 5), ('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=1\r\n', None, None, 3600), ('AT+CSMS=1\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CSDH=1\r\n', None, None, 5), ('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=1\r\n', None, None, 3600), ('AT+CSMS=1\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CSNS=0\r\n', None, None, 5), ('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CMGF=1\r\n', None, None, 3600), ('AT+CSMS=1\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CMGF=1\r\n', None, None, 5), ('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CSMS=1\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CSMS=1\r\n', None, None, 5), ('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CLIP=0\r\n', None, None, 5), ('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+COLP=0\r\n', None, None, 5), ('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CCWA=0\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([('AT+CCWA=0\r\n', None, None, 3600)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CCWA=0\r\n', None, None, 5), ('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930>: nothing in request queue)
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0> queue is: deque([]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.CallChannel object at 0x5d74d0>: nothing in request queue)
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CLIP=1\r\n', None, None, 5), ('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
ogsmd    INFO     NOTIFY SERVER: ALL CHANNELS OK, START TRAFFICING :)
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+COLP=1\r\n', None, None, 5), ('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CCWA=1\r\n', None, None, 5), ('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CSSN=1,1\r\n', None, None, 5), ('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CTZU=1\r\n', None, None, 5), ('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CTZR=1\r\n', None, None, 5), ('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CREG=2\r\n', None, None, 5), ('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CAOC=2\r\n', None, None, 5), ('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CGEREP=2,1\r\n', None, None, 5), ('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CGREG=2\r\n', None, None, 5), ('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT%CPI=3\r\n', None, None, 5), ('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT%CSCN=1,2,1,2\r\n', None, None, 5), ('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT%CSQ=1\r\n', None, None, 5), ('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT%CNIV=1\r\n', None, None, 5), ('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT%CGEREP=1\r\n', None, None, 5), ('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT%CGREG=3\r\n', None, None, 5), ('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT%CSTAT=1\r\n', None, None, 5), ('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT@ST="-26"\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0>: nothing in request queue)
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to IDLE_DIM
Enabled GSM
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7ab0>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7ab0>>, 5)]))
ogsmd    INFO     auth status changed to SIM PIN
ogsmd.modem.unsolicited INFO     subsystem PHB readyness now False
ogsmd    INFO     sim ready status False
ogsmd.modem.unsolicited INFO     full readyness now False
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CFUN=1\r\n', <bound method DeviceSetAntennaPower.responseFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7ab0>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7ab0>>, 5)]))
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to IDLE_PRELOCK
ogsmd.modem.unsolicited INFO     subsystem PHB readyness now False
ogsmd.modem.unsolicited INFO     full readyness now False
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse2 of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7ab0>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7ab0>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CPIN?\r\n', <bound method SimGetAuthStatus.responseFromChannel of <ogsmd.modems.abstract.mediator.SimGetAuthStatus object at 0x5d7970>>, <bound method SimGetAuthStatus.errorFromChannel of <ogsmd.modems.abstract.mediator.SimGetAuthStatus object at 0x5d7970>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930>: nothing in request queue)
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to LOCK
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to SUSPEND
no elements in queue: starting idle task.
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to BUSY
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
frameworkd.controller DEBUG    alive and kicking
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to IDLE
no elements in queue: starting idle task.
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to BUSY
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
no elements in queue: starting idle task.
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
_processElement()
no more elements: stopping idle task.
_processElement()
no more elements: stopping idle task.
_processElement()
no more elements: stopping idle task.
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to IDLE
no elements in queue: starting idle task.
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to BUSY
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
no elements in queue: starting idle task.
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([(u'AT+CPIN="PIN_XXXX"\r\n', <bound method SimSendAuthCode.responseFromChannel of <ogsmd.modems.abstract.mediator.SimSendAuthCode object at 0x5d7ab0>>, <bound method SimSendAuthCode.errorFromChannel of <ogsmd.modems.abstract.mediator.SimSendAuthCode object at 0x5d7ab0>>, 7)]))
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
got an element from the queue
_processElement()
no more elements: stopping idle task.
ogsmd.modem.unsolicited INFO     subsystem PHB readyness now False
ogsmd.modem.unsolicited INFO     full readyness now False
ogsmd.modem.unsolicited INFO     subsystem PHB readyness now False
ogsmd.modem.unsolicited INFO     full readyness now False
ogsmd.modem.unsolicited INFO     subsystem PHB readyness now False
ogsmd.modem.unsolicited INFO     full readyness now False
ogsmd    INFO     auth status changed to READY
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930>: nothing in request queue)
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7a10>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7a10>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CFUN=1\r\n', <bound method DeviceSetAntennaPower.responseFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7a10>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7a10>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse2 of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7a10>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x5d7a10>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+COPS=0,0\r\n', <bound method NetworkRegister.responseFromChannel of <ogsmd.modems.abstract.mediator.NetworkRegister object at 0x66b210>>, <bound method NetworkRegister.errorFromChannel of <ogsmd.modems.abstract.mediator.NetworkRegister object at 0x66b210>>, 15)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT+CNMI=2,1,2,1,1\r\n', None, None, 5), ('AT%CBHZ=1\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([('AT%CBHZ=1\r\n', None, None, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0> queue is: deque([]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.UnsolicitedResponseChannel object at 0x5d77f0>: nothing in request queue)
ogsmd.modem.unsolicited INFO     full readyness now False
ogsmd.modem.unsolicited INFO     subsystem PHB readyness now False
ogsmd.modem.unsolicited INFO     full readyness now False
ogsmd.modem.unsolicited INFO     subsystem SMS readyness now True
ogsmd.modem.unsolicited INFO     full readyness now False
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to IDLE
ogsmd    INFO     org.freesmartphone.GSM.Network.SignalStrength: 83
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b570>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b570>>, 5), ('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b630>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b630>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b630>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b630>>, 5), ('AT+CREG?;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b570>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b570>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CREG?;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b570>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b570>>, 5), ('AT+CREG?;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b630>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b630>>, 5)]))
ogsmd    INFO     org.freesmartphone.GSM.Network.Status: {'strength': 83, 'mode': 'automatic', 'registration': 'denied'}
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CREG?;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b630>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x66b630>>, 5)]))
ogsmd    INFO     org.freesmartphone.GSM.Network.Status: {'strength': 83, 'mode': 'automatic', 'registration': 'denied'}
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930>: nothing in request queue)
ogsmd.modem.unsolicited INFO     subsystem PHB readyness now True
ogsmd    INFO     sim ready status True
ogsmd.modem.unsolicited INFO     full readyness now True
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CPBS="SM";+CPBR=?\r\n', <bound method SimGetPhonebookInfo.responseFromChannel of <ogsmd.modems.abstract.mediator.SimGetPhonebookInfo object at 0x66b530>>, <bound method SimGetPhonebookInfo.errorFromChannel of <ogsmd.modems.abstract.mediator.SimGetPhonebookInfo object at 0x66b530>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CMGL="ALL"\r\n', <bound method SimRetrieveMessagebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x66b3f0>>, <bound method SimRetrieveMessagebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x66b3f0>>, 5), ('AT+CPBS="SM";+CPBR=1,250\r\n', <bound method SimRetrievePhonebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrievePhonebook object at 0x5d7a10>>, <bound method SimRetrievePhonebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrievePhonebook object at 0x5d7a10>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([('AT+CPBS="SM";+CPBR=1,250\r\n', <bound method SimRetrievePhonebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrievePhonebook object at 0x5d7a10>>, <bound method SimRetrievePhonebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrievePhonebook object at 0x5d7a10>>, 5)]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930> queue is: deque([]))
(<framework.subsystems.ogsmd.modems.ti_calypso.channel.MiscChannel object at 0x5d7930>: nothing in request queue)
frameworkd.controller DEBUG    alive and kicking
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to IDLE_DIM
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to IDLE_PRELOCK
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to LOCK
odeviced.idlenotifier INFO     framework.subsystems.odeviced.idlenotifier state change to SUSPEND
frameworkd.controller DEBUG    alive and kicking
frameworkd.controller DEBUG    alive and kicking