2009/11/7 Neil Jerram <neiljer...@googlemail.com>:
> 2009.11.07 13:38:04.692 oeventsd.action      ERROR    method Suspend
> emited error: org.freedesktop.DBus.Error.NoReply: Did not receive a
> reply. Possible causes include: the remote application did not send a
> reply, the message bus security policy blocked the reply, the reply
> timeout expired, or the network connection was broken.
>
> Does that provide a clue?

I'm now running with DEBUG frameworkd logging and have some more diags
for this problem.

First occurrence:

2009.11.17 20:23:25.3 odeviced.idlenotifier DEBUG    active = False
2009.11.17 20:23:25.139 ousaged              INFO     suspending all
resources...
2009.11.17 20:23:25.144 ousaged              DEBUG    suspending WiFi
2009.11.17 20:23:25.216 frameworkd.resource  DEBUG    processing
command 'suspend' for resource
'<odeviced.powercontrol_neo.NeoWifiPowerControl at
/org/freesmartphone/Device/PowerControl/WiFi at 0x556590>' (present
status=disabled)
2009.11.17 20:23:25.241 ousaged              DEBUG    suspending Display
2009.11.17 20:23:25.315 frameworkd.resource  DEBUG    processing
command 'suspend' for resource '<odeviced.idlenotifier.DisplayResource
at /org/freesmartphone/Device/Display at 0x480450>' (present
status=disabled)
2009.11.17 20:23:25.341 ousaged              DEBUG    suspending Bluetooth
2009.11.17 20:23:25.413 frameworkd.resource  DEBUG    processing
command 'suspend' for resource
'<odeviced.powercontrol_neo.NeoBluetoothPowerControl at
/org/freesmartphone/Device/PowerControl/Bluetooth at 0x5563f0>'
(present status=disabled)
2009.11.17 20:23:25.438 ousaged              DEBUG    suspending TEST
2009.11.17 20:23:25.514 frameworkd.resource  DEBUG    processing
command 'suspend' for resource '<testing.testing.Resource at
/org/freesmartphone/Testing at 0x9354d0>' (present status=disabled)
2009.11.17 20:23:25.536 ousaged              DEBUG    suspending GSM
2009.11.17 20:23:25.609 frameworkd.resource  DEBUG    processing
command 'suspend' for resource '<ogsmd.device.Device at
/org/freesmartphone/GSM/Device at 0x738b10>' (present status=enabled)
2009.11.17 20:23:25.616 frameworkd.resource  INFO     setting resource
status for GSM from enabled to suspending
2009.11.17 20:23:25.638 ogsmd.modem.abstract DEBUG
prepareForSuspend ACK from channel <MiscChannel via /dev/pts/0>
received
2009.11.17 20:23:25.644 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT+CNMI=2,1,0,0,0\r\n': set(['+CNMI'])
2009.11.17 20:23:25.652 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT+CTZU=0\r\n': set(['+CTZU'])
2009.11.17 20:23:25.672 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT+CTZR=0\r\n': set(['+CTZR'])
2009.11.17 20:23:25.680 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT+CREG=0\r\n': set(['+CREG'])
2009.11.17 20:23:25.687 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT+CGREG=0\r\n': set(['+CGREG'])
2009.11.17 20:23:25.707 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT+CGEREP=0,0\r\n': set(['+CGEREP'])
2009.11.17 20:23:25.714 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT%CSQ=0\r\n': set(['%CSQ'])
2009.11.17 20:23:25.734 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT%CPRI=0\r\n': set(['%CPRI'])
2009.11.17 20:23:25.742 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: Autogenerated prefixes
for command 'AT%CBHZ=0\r\n': set(['%CBHZ'])
2009.11.17 20:23:25.749 ogsmd.modem.abstract DEBUG
prepareForSuspend ACK from channel <CallChannel via /dev/pts/2>
received
2009.11.17 20:23:25.769 ogsmd.channel        DEBUG
<UnsolicitedResponseChannel via /dev/pts/1>: sending 19 bytes:
'AT+CNMI=2,1,0,0,0\r\n'
2009.11.17 20:23:26.13 odeviced.idlenotifier DEBUG    active = False

Second occurrence:

2009.11.17 20:25:01.8 odeviced.idlenotifier DEBUG    active = False
2009.11.17 20:25:01.858 ousaged              INFO     suspending all
resources...
2009.11.17 20:25:01.863 ousaged              DEBUG    suspending WiFi
2009.11.17 20:25:01.987 frameworkd.resource  DEBUG    processing
command 'suspend' for resource
'<odeviced.powercontrol_neo.NeoWifiPowerControl at
/org/freesmartphone/Device/PowerControl/WiFi at 0x556590>' (present
status=disabled)
2009.11.17 20:25:02.17 odeviced.idlenotifier DEBUG    active = False
2009.11.17 20:25:02.24 ousaged              DEBUG    suspending Display
2009.11.17 20:25:02.93 frameworkd.resource  DEBUG    processing
command 'suspend' for resource '<odeviced.idlenotifier.DisplayResource
at /org/freesmartphone/Device/Display at 0x480450>' (present
status=disabled)
2009.11.17 20:25:02.118 ousaged              DEBUG    suspending Bluetooth
2009.11.17 20:25:02.191 frameworkd.resource  DEBUG    processing
command 'suspend' for resource
'<odeviced.powercontrol_neo.NeoBluetoothPowerControl at
/org/freesmartphone/Device/PowerControl/Bluetooth at 0x5563f0>'
(present status=disabled)
2009.11.17 20:25:02.216 ousaged              DEBUG    suspending TEST
2009.11.17 20:25:02.288 frameworkd.resource  DEBUG    processing
command 'suspend' for resource '<testing.testing.Resource at
/org/freesmartphone/Testing at 0x9354d0>' (present status=disabled)
2009.11.17 20:25:02.313 ousaged              DEBUG    suspending GSM
2009.11.17 20:25:02.385 frameworkd.resource  DEBUG    processing
command 'suspend' for resource '<ogsmd.device.Device at
/org/freesmartphone/GSM/Device at 0x738b10>' (present
status=suspending)
2009.11.17 20:25:02.391 frameworkd.resource  INFO     setting resource
status for GSM from suspending to suspending
2009.11.17 20:25:02.408 ogsmd.modem.abstract DEBUG
prepareForSuspend ACK from channel <MiscChannel via /dev/pts/0>
received
2009.11.17 20:25:02.437 ogsmd.modem.abstract DEBUG
prepareForSuspend ACK from channel <CallChannel via /dev/pts/2>
received
2009.11.17 20:25:03.9 odeviced.idlenotifier DEBUG    active = False

In both cases the direction to suspend came from running

mdbus -s org.freesmartphone.ousaged /org/freesmartphone/Usage
org.freesmartphone.Usage.Suspend

and the output from this command was

/org/freesmartphone/Usage: Suspend failed:
org.freedesktop.DBus.Error.NoReply (Did not receive a reply. Possible
causes include: the remote application did not send a reply, the
message bus security policy blocked the reply, the reply timeout
expired, or the network connection was broken.)

The picture I'm getting is that odeviced fans out the suspend
direction to all the frameworkd subcomponents, and presumably waits
for a successful response from all of those before it responds itself.
 So maybe, if one of the subcomponents doesn't respond, odeviced
doesn't respond to whoever requested the suspend.

On the other hand, given that the point is to suspend, maybe it's
normal for the requester not to get any response.  Perhaps everything
logged above is normal, and the problem is just that whichever
component is responsible for switching off the phone, doesn't do that.

Any relevant information would be much appreciated!

Regards,
       Neil

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

Reply via email to