Cannot send MMS when nuntium started before ofono

[Alfonso Sanchez-Beato]

Cannot send MMS in vivid image #163, arale.

$ system-image-cli -i
current build number: 163
device name: m75
channel: ubuntu-touch/vivid-proposed
last update: 2015-04-06 12:29:08
version version: 163

The reason might be that nuntium is being started before ofono:

phablet@ubuntu-phablet:~/.cache/upstart$ cat nuntium.log

2015/04/07 11:20:06 Received GetServices()
2015/04/07 11:20:42 Using session bus on :1.13
2015/04/07 11:20:42 Registered :1.13 on bus as org.ofono.mms
2015/04/07 11:20:42 Using system bus on :1.27
2015/04/07 11:20:42 Cannot preemptively add modems: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
2015/04/07 11:20:42 Received GetServices()

syslog:

Apr 7 11:20:45 ubuntu-phablet ofonod[1992]: oFono version 1.12

Re-starting nuntium makes sending MMS possible again.

Usually nuntium's log show this message instead:

2015/04/07 11:16:29 Using session bus on :1.11
2015/04/07 11:16:29 Registered :1.11 on bus as org.ofono.mms
2015/04/07 11:16:29 Using system bus on :1.32
2015/04/07 11:16:29 Initializing modem /ril_0

[Roberto Mier Escandon (rmescandon)]

Cannot send in Krillin using vivid r185 when configured using pepephone as carrier

nuntium.log -> http://paste.ubuntu.com/10831777/:
2015/04/16 08:22:28 Received GetServices()
2015/04/16 08:23:40 Using session bus on :1.13
2015/04/16 08:23:40 Registered :1.13 on bus as org.ofono.mms
2015/04/16 08:23:40 Using system bus on :1.29
2015/04/16 08:23:40 Cannot preemptively add modems: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
2015/04/16 08:23:40 Received GetServices()
2015/04/16 08:23:40 Received GetServices()

contexts-list (look at the value for MessageProxy in context 3) -> http://paste.ubuntu.com/10831765/:
[ /ril_1 ]
[ /ril_0 ]
    [ /ril_0/context1 ]
        Username =
        Settings = { DomainNameServers=80.58.61.250,80.58.61.254, Interface=ccmni0 Address=10.56.73.122 Netmask=255.255.255.0 Method=static Gateway=10.56.73.122 }
        IPv6.Settings = { }
        Active = 1
        Type = internet
        Protocol = ip
        Password =
        Name = Pepephone
        Preferred = 0
        AccessPointName = gprs.pepephone.com

    [ /ril_0/context3 ]
        IPv6.Settings = { }
        AccessPointName = gprs.pepephone.com
        MessageProxy = 10.138.255.43:8080:undefined
        MessageCenter = http://www.pepephone.com
        Password =
        Name = ___ubuntu_custom_apn_mms
        Active = 0
        Type = mms
        Protocol = ip
        Settings = { }
        Username =
        Preferred = 0

    [ /ril_0/context4 ]
        Username =
        Settings = { }
        IPv6.Settings = { }
        Active = 0
        Type = internet
        Protocol = ip
        Password =
        Name = ___ubuntu_custom_apn_internet
        Preferred = 0
        AccessPointName = gprsmov.pepephone.com

[Alfonso Sanchez-Beato (alfonsosanchezbeato)]

I have discovered that if I dowload and build latest nuntium and its dependencies:

https://github.com/ubuntu-phonedations/nuntium
lp:go-dbus/v1
lp:go-xdg/v0
lp:udm

, using that binary the error does not happen anymore (nuntium can handle the case of ofono starting after it).

My guess is that some change in go-dbus has fixed the issue.

[Alfonso Sanchez-Beato (alfonsosanchezbeato)]

... so we must land a new nuntium package to solve this.

[Alfonso Sanchez-Beato (alfonsosanchezbeato)]

Probably this change fixed this

http://bazaar.launchpad.net/~go-dbus/go-dbus/v1/revision/131 (2014-11-13)

Current nuntium is from 2014-10-13

(side thought: we need shared libraries for GO ASAP... fortunately people is already working on this :-)

[Ricardo Salveti (rsalveti)]

Current nuntium (vivid) is from 2015-03-10. The golang-go-dbus package is bzr20150122-1, already including the change you pasted.

Also, how did you build nuntium? AFAIK we're using gccgo with the package.

[Alfonso Sanchez-Beato (alfonsosanchezbeato)]

Ok, for some unknown reason I downloaded nuntium_0.1+14.10.20141013-0ubuntu1 instead of the last version. Weird, because I did it in arale so it is vivid. "apt-get source nuntium" gives me now 1.4-0ubuntu6. I did "apt-get build-dep nuntium" and used the installed go, which seems to be gogcc.

Anyway, the error seems still seems to go away if using latest libraries. Doing in arale/vivid 12:

stop nuntium
sudo stop ofono
/usr/bin/nuntium
(then, from another terminal)
sudo start ofono OFONO_RIL_DEVICE=mtk

produces as last printed line by nuntium

2015/04/17 06:56:18 Using session bus on :1.115
2015/04/17 06:56:18 Registered :1.115 on bus as org.ofono.mms
2015/04/17 06:56:18 Using system bus on :1.135
2015/04/17 06:56:18 Cannot preemptively add modems: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files

Changing /usr/bin/nuntium and using the one I compiled the last lines are:

2015/04/17 06:57:49 Using session bus on :1.116
2015/04/17 06:57:49 Registered :1.116 on bus as org.ofono.mms
2015/04/17 06:57:49 Using system bus on :1.137
2015/04/17 06:57:49 Cannot preemptively add modems: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
2015/04/17 06:57:54 Initializing modem /ril_0
2015/04/17 06:57:55 Modem online: true

I also compiled the source package and you still get the error. Probably I should not make the error again of pointing out a specific change, but go-dbus change #136 (2015-02-03) seems like something that could have fixed this.

Finally, recompiling fixes the race condition on boot, but even using latest go-dbus, if I do after both ofono and nuntium have started

restart ofono OFONO_RIL_DEVICE=mtk

then nuntium removes the modem instance, but does not re-create it after ofono starts again. So nuntium would still have a bug when ofono restarts or crashes and is re-spawned.

[Manuel de la Peña (mandel)]

AFAIK is ofono crashes we agreed that the phone would reboot, is it no that the case? If it is not, we indeed have a bug.

[Ricardo Salveti (rsalveti)]

Even if it reboots this is still a valid bug. It respawns a few times before actually rebooting, so this is a valid scenario.

[Ricardo Salveti (rsalveti)]

Rebuilt nuntium at https://launchpad.net/~ci-train-ppa-service/+archive/ubuntu/landing-006 with latest go-dbus, using golang instead of gcc go (in order to decrease image size). Please give that a try.

[Alfonso Sanchez-Beato (alfonsosanchezbeato)]

Silo 6 solves the issue on boot, we should land it.

However, as said in comment #6 nuntium is not able to recover from an ofono re-start (this is not deterministic, in some cases it is actually able to recover, but not always), so we can keep the bug open until that gets sorted out.

[Sergio Schvezov (sergiusens)]

On Wed, Apr 22, 2015 at 5:02 AM, Alfonso Sanchez-Beato <
<email address hidden>> wrote:

> Silo 6 solves the issue on boot, we should land it.
>
> However, as said in comment #6 nuntium is not able to recover from an
> ofono re-start (this is not deterministic, in some cases it is actually
> able to recover, but not always), so we can keep the bug open until that
> gets sorted out.
>

Aren't ofono restarts supposed to reboot the phone anyways?

>
> --
> You received this bug notification because you are a member of Canonical
> Phone Foundations, which is a bug assignee.
> Matching subscriptions: nuntium
> https://bugs.launchpad.net/bugs/1441135
>
> Title:
> Cannot send MMS when nuntium started before ofono
>
> To manage notifications about this bug go to:
>
> https://bugs.launchpad.net/canonical-devices-system-image/+bug/1441135/+subscriptions
>

[Alfonso Sanchez-Beato (alfonsosanchezbeato)]

@Sergio

No, it does not. Unless there are a lot of re-spawns in a short amount of time, as pointed out in comment #8.