As one of those other users I'm happy to send you logs.

Thanks.

OK here goes. Actually unless you can see something I can't the surprising thing is that there is no sign of the incoming message in the logs at all. But (and this may provide a clue) yesterday I tried a different SIM belonging to a different telco and bingo, it worked! So perhaps this issue is telco-dependent? But also bear in mind that I have tried numerous distros on my Freerunner and the only one that has given me this problem has been Debian/zhone. FYI my normal telco, the one giving me problems, is called Macquarie Telecom and resells airtime of Vodafone AU. The one that worked was Telstra.

I won't send you the framework log because it's just too long. If you want me to grep for something then let me know. But as I say there is no sign of any incoming messages in it.

2009-10-21 10:55:16,914 DEBUG GUI init
2009-10-21 10:55:19,778 DEBUG GUI init done
2009-10-21 10:55:19,785 DEBUG entering mainloop
2009-10-21 10:55:20,895 DEBUG dbus_objectInit...
2009-10-21 10:55:21,103 DEBUG usage ok: <Interface <ProxyObject wrapping 
<dbus._dbus.SystemBus (system) at 0x405290c0> :1.22 /org/freesmartphone/Usage at 
0x4051a430> implementing 'org.freesmartphone.Usage' at 0x4051a4f0>
2009-10-21 10:55:21,303 DEBUG gps ok: <Interface <ProxyObject wrapping <dbus._dbus.SystemBus (system) at 0x405290c0> 
:1.14 /org/freedesktop/Gypsy at 0x4051a730> implementing 'org.freedesktop.Gypsy.Accuracy' at 0x4051a910>, <Interface 
<ProxyObject wrapping <dbus._dbus.SystemBus (system) at 0x405290c0> :1.14 /org/freedesktop/Gypsy at 0x4051a730> 
implementing 'org.freedesktop.Gypsy.Position' at 0x4051ab90>, <Interface <ProxyObject wrapping <dbus._dbus.SystemBus 
(system) at 0x405290c0> :1.14 /org/freedesktop/Gypsy at 0x4051a730> implementing 'org.freedesktop.Gypsy.Satellite' at 
0x4051acd0>
2009-10-21 10:55:21,524 DEBUG gsm ok: <Interface <ProxyObject wrapping 
<dbus._dbus.SystemBus (system) at 0x405290c0> :1.15 /org/freesmartphone/GSM/Device at 
0x4052e0d0> implementing 'org.freesmartphone.GSM.Network' at 0x4052e2b0>
2009-10-21 10:55:21,632 DEBUG device ok: <Interface <ProxyObject wrapping 
<dbus._dbus.SystemBus (system) at 0x405290c0> :1.9 /org/freesmartphone/Device at 
0x4052e8d0> implementing 'org.freesmartphone.Device' at 0x4052e990>
2009-10-21 10:55:21,658 DEBUG preferences ok: <Interface <ProxyObject wrapping 
<dbus._dbus.SystemBus (system) at 0x405290c0> :1.20 /org/freesmartphone/Preferences at 
0x4052edb0> implementing 'org.freesmartphone.Preferences' at 0x4052ee70>
2009-10-21 10:55:21,664 DEBUG failcount = 0
2009-10-21 10:55:21,700 DEBUG dbus_objectInitOK!
2009-10-21 10:55:21,715 DEBUG Requesting resource list
2009-10-21 10:55:21,798 DEBUG Requesting resource GSM
2009-10-21 10:55:25,181 INFO IDLE STATE = idle_dim
2009-10-21 10:55:38,646 DEBUG Requested resource GSM
2009-10-21 10:55:38,659 DEBUG Turning on Antenna
2009-10-21 10:55:42,981 DEBUG SIM is ready: 0
2009-10-21 10:55:42,988 DEBUG SIM is ready: 0
2009-10-21 10:55:53,135 INFO Antenna power OK. Registering to network now.
2009-10-21 10:55:53,142 DEBUG Registering to network
2009-10-21 10:55:59,528 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(89, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 10:55:59,534 DEBUG Registered: vodafone AU
2009-10-21 10:55:59,711 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(89, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 10:55:59,718 DEBUG Registered: vodafone AU
2009-10-21 10:56:39,971 DEBUG SIM is ready: 1
2009-10-21 10:56:39,978 INFO retrieving phonebook...
2009-10-21 10:56:39,989 DEBUG SIM is ready: 1
2009-10-21 10:56:40,021 INFO retrieving messagebook...
2009-10-21 10:56:48,238 INFO retrieved messagebook: <snipped>
2009-10-21 10:56:52,840 INFO retrieved phonebook: <snipped>
2009-10-21 10:57:15,517 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B72', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(93, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 10:57:15,524 DEBUG Registered: vodafone AU
2009-10-21 10:57:30,347 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(83, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 10:57:30,353 DEBUG Registered: vodafone AU
2009-10-21 10:59:42,428 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B72', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(90, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 10:59:42,436 DEBUG Registered: vodafone AU
2009-10-21 10:59:53,697 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(84, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 10:59:53,703 DEBUG Registered: vodafone AU
2009-10-21 11:00:54,402 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'773B', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(90, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:00:54,408 DEBUG Registered: vodafone AU
2009-10-21 11:01:09,026 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(81, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:01:09,033 DEBUG Registered: vodafone AU
2009-10-21 11:02:26,720 INFO network status changed: 
dbus.Dictionary({dbus.String(u'registration'): dbus.String(u'unregistered', 
variant_level=1), dbus.String(u'strength'): dbus.Int32(0, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:02:26,732 DEBUG Failed to register to network
2009-10-21 11:02:29,771 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:02:29,778 DEBUG Registered: vodafone AU
2009-10-21 11:03:26,031 INFO network status changed: 
dbus.Dictionary({dbus.String(u'registration'): dbus.String(u'unregistered', 
variant_level=1), dbus.String(u'strength'): dbus.Int32(0, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:03:26,038 DEBUG Failed to register to network
2009-10-21 11:03:28,416 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:03:28,423 DEBUG Registered: vodafone AU
2009-10-21 11:03:59,935 INFO network status changed: 
dbus.Dictionary({dbus.String(u'registration'): dbus.String(u'unregistered', 
variant_level=1), dbus.String(u'strength'): dbus.Int32(0, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:03:59,941 DEBUG Failed to register to network
2009-10-21 11:04:02,491 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:04:02,497 DEBUG Registered: vodafone AU
2009-10-21 11:04:52,387 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'76A3', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(89, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:04:52,394 DEBUG Registered: vodafone AU
2009-10-21 11:05:08,203 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(84, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:05:08,210 DEBUG Registered: vodafone AU
2009-10-21 11:05:30,423 DEBUG transition to sms
2009-10-21 11:05:31,315 INFO IDLE STATE = busy
2009-10-21 11:05:32,028 INFO IDLE STATE = idle
2009-10-21 11:05:41,659 INFO IDLE STATE = busy
2009-10-21 11:05:42,048 INFO IDLE STATE = idle
2009-10-21 11:05:43,759 INFO IDLE STATE = busy
2009-10-21 11:05:44,031 INFO IDLE STATE = idle
2009-10-21 11:05:45,456 INFO IDLE STATE = busy
2009-10-21 11:05:47,049 INFO IDLE STATE = idle
2009-10-21 11:05:48,226 DEBUG transition to main
2009-10-21 11:05:49,044 INFO IDLE STATE = busy
2009-10-21 11:05:50,028 INFO IDLE STATE = idle
2009-10-21 11:05:59,028 INFO IDLE STATE = idle_dim
2009-10-21 11:06:03,221 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'773B', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(89, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:06:03,227 DEBUG Registered: vodafone AU
2009-10-21 11:06:17,867 INFO network status changed: 
dbus.Dictionary({dbus.String(u'code'): dbus.String(u'50503', variant_level=1), 
dbus.String(u'cid'): dbus.String(u'7B71', variant_level=1), 
dbus.String(u'act'): dbus.String(u'GSM', variant_level=1), 
dbus.String(u'provider'): dbus.String(u'vodafone AU', variant_level=1), 
dbus.String(u'lac'): dbus.String(u'0C29', variant_level=1), 
dbus.String(u'strength'): dbus.Int32(83, variant_level=1), 
dbus.String(u'mode'): dbus.String(u'automatic', variant_level=1), 
dbus.String(u'registration'): dbus.String(u'home', variant_level=1)}, 
signature=dbus.Signature('sv'))
2009-10-21 11:06:17,874 DEBUG Registered: vodafone AU

Now here's the weird bit. As you can see from the command below, the GSM chip did in fact receive an incoming test message at 10:57:59.

mdbus -s org.freesmartphone.ogsmd /org/freesmartphone/GSM/Device 
org.freesmartphone.GSM.SIM.RetrieveMessage 3
(   'read',
    '+447624803777',
    "Thanks for testing Clickatell's gateway. You can try our two-way messaging by 
replying to this text.Any message, anywhere. www.clickatell.com",
    {   'alphabet': 'gsm_default',
        'more-messages-to-send': False,
        'pid': 0,
        'reply-path': False,
        'status-report-indicator': False,
        'timestamp': 'Wed Oct 21 10:57:59 2009 +1100',
        'type': 'sms-deliver'})

Cheers,
Jonathan



_______________________________________________
Smartphones-userland mailing list
Smartphones-userland@linuxtogo.org
http://lists.linuxtogo.org/cgi-bin/mailman/listinfo/smartphones-userland

Reply via email to