Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)

From: Dan Williams <dcbw redhat com>
To: Ali Nematollahi <alirezan1 gmail com>
Cc: networkmanager-list gnome org
Subject: Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
Date: Fri, 11 Mar 2016 14:52:48 -0600

On Fri, 2016-03-11 at 10:53 -0800, Ali Nematollahi wrote:

Hi Dan

I did the changes, and it still fails. I made the wait a little
longer,
still fails. I sent an mmcli AT command to the modem, it fails to
respond:


<snip>

Isn't that a response to ^SCFG?


Yes, it is.  But notice how it doesn't actually come as a response to
the AT^SCFG=? request, but only when ModemManager sends the next
request.

Dan





On Fri, Mar 11, 2016 at 6:59 AM, Dan Williams <dcbw redhat com>
wrote:


On Thu, 2016-03-10 at 17:34 -0800, Ali Nematollahi wrote:


So I've started looking at the code and I looked for the error
message that
says Missing +CGDCONT prefix. I added debugging code to print out
the
"response" and the result of g_str_has_prefix () function call in
src/mm-modem-helpers.c: mm_3gpp_parse_cgdcont_test_response()

Here is my debugging output (highlighted in red).
The response clearly has "+CGDCONT" at the beginning...but
g_str_has_prefix() fails to read it. Is it because my glib is
old? Or
could
it be because it comes out with <CR><LF> before it and
g_str_has_prefix()
is confused as a result??

The reason it fails is because of the "Serial command timed out";
the
modem doesn't respond to the AT^SCFG request within the expected
time,
so ModemManager gives up.  Then the response actually does happen a
bit
later, but MM has already sent the CGDCONT request, and expects a
CGDCONT reply instead of the ^SCFG reply.  So we probably just need
to
increase the timeout for this modem.  Can you try this change?

In plugins/cinterion/mm-broadband-modem-cinterion.c:

static void
load_supported_bands (MMIfaceModem *self,
                      GAsyncReadyCallback callback,
                      gpointer user_data)
{
    GSimpleAsyncResult *simple;

    simple = g_simple_async_result_new (G_OBJECT (self),
                                        callback,
                                        user_data,
                                        load_supported_bands);

    mm_base_modem_at_command (MM_BASE_MODEM (self),
                              "AT^SCFG=?",
-                             3,
+                             6,
                              FALSE,
                              (GAsyncReadyCallback)scfg_test_ready,
                              simple);
}

and I bet it'll start working.

Dan






Manager[4969]: <debug> [1457629898.039154] [mm-port-serial-
at.c:440]
debug_log(): (ttyUSB2): --> 'AT^SCFG=?<CR>'
ModemManager[4969]: <warn>  [1457629901.672443] [mm-iface-
modem.c:3961]
load_supported_bands_ready(): couldn't load Supported Bands:
'Serial
command timed out'
ModemManager[4969]: <debug> [1457629901.674453] [mm-broadband-
modem.c:1675]
modem_load_supported_ip_families(): loading supported IP
families...
ModemManager[4969]: <debug> [1457629901.675897] [mm-port-
serial.c:1237]
mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[4969]: <debug> [1457629901.676875] [mm-port-
serial.c:1294]
_close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[4969]: <debug> [1457629901.677831] [mm-port-serial-
at.c:440]
debug_log(): (ttyUSB2): --> 'AT+CGDCONT=?<CR>'
ModemManager[4969]: <debug> [1457629901.712009] [mm-port-serial-
at.c:440]
debug_log(): (ttyUSB2): <-- '<CR><LF>^SCFG:
"Audio/Loop",("0","1")<CR><LF>^SCFG: "Call/ECC",("0"-
"255")<CR><LF>^SCFG:
"Call/Speech/Codec",("0","1")<CR><LF>^SCFG:
"GPRS/Auth",("0","1","2")<CR><LF>^SCFG:
"GPRS/AutoAttach",("disabled","enabled")<CR><LF>^SCFG:
"GPRS/MaxDataRate/HSDPA",("0","1")<CR><LF>^SCFG:
"GPRS/MaxDataRate/HSUPA",("0","1")<CR><LF>^SCFG:
"Ident/Manufacturer",(25)<CR><LF>^SCFG:
"Ident/Product",(25)<CR><LF>^SCFG:
"MEopMode/Airplane",("off","on")<CR><LF>^SCFG:
"MEopMode/CregRoam",("0","1")<CR><LF>^SCFG:
"MEopMode/CFUN",("0","1")<CR><LF>^SCFG:
"MEopMode/PowerMgmt/LCI",("disabled","enabled")<CR><LF>^SCFG:
"MEopMode/PowerMgmt/VExt",("high","low")<CR><LF>^SCFG:
"MEopMode/PwrSave",("disabled","enabled"),("0-600"),("1-
36000")<CR><LF>^SCFG:
"MEopMode/RingOnData",("on","off")<CR><LF>^SCFG:
"MEopMode/RingUrcOnCall",("on","off")<CR><LF>^SCFG:
"MEShutdown/OnIgnition",("on","off")<CR><LF>^SCFG:
"Radio/Band",("4-108","0-1")<CR><LF>^SCFG:
"Radio/NWSM",("0","1","2")<CR><LF>^SCFG:
"Radio/OutputPowerReduction",("4"-"8")<CR><LF>^SCFG:
"Serial/USB/DDD",("0","1"),("0"),(4),(4),(4),(63),(63),(4)<CR><LF

^SC

FG:
"URC/DstIfc",("mdm","app")<CR><LF>^SCFG:
"URC/Datamode/Ringline",("off","on")<CR><LF>^SCFG:
"URC/Ringline",("off","local","asc0","wakeup")<CR><LF>^SCFG:
"URC/Ringline/ActiveTime",("0","1","2","keep")<CR><LF><CR><LF>OK<
CR><
LF><CR><LF>+CGDCONT:
(1-16,101-116),"IP",,,(0),(0-4)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[4969]: <debug> [1457629901.714967] [mm-modem-
helpers.c:762]
mm_3gpp_parse_cgdcont_test_response(): *************
mm_3gpp_parse_cgdcont_test_response *************
ModemManager[4969]: <debug> [1457629901.716188] [mm-modem-
helpers.c:763]
mm_3gpp_parse_cgdcont_test_response(): response = ^SCFG:
"Audio/Loop",("0","1")
^SCFG: "Call/ECC",("0"-"255")
^SCFG: "Call/Speech/Codec",("0","1")
^SCFG: "GPRS/Auth",("0","1","2")
^SCFG: "GPRS/AutoAttach",("disabled","enabled")
^SCFG: "GPRS/MaxDataRate/HSDPA",("0","1")
^SCFG: "GPRS/MaxDataRate/HSUPA",("0","1")
^SCFG: "Ident/Manufacturer",(25)
^SCFG: "Ident/Product",(25)
^SCFG: "MEopMode/Airplane",("off","on")
^SCFG: "MEopMode/CregRoam",("0","1")
^SCFG: "MEopMode/CFUN",("0","1")
^SCFG: "MEopMode/PowerMgmt/LCI",("disabled","enabled")
^SCFG: "MEopMode/PowerMgmt/VExt",("high","low")
^SCFG: "MEopMode/PwrSave",("disabled","enabled"),("0-600"),("1-
36000")
^SCFG: "MEopMode/RingOnData",("on","off")
^SCFG: "MEopMode/RingUrcOnCall",("on","off")
^SCFG: "MEShutdown/OnIgnition",("on","off")
^SCFG: "Radio/Band",("4-108","0-1")
^SCFG: "Radio/NWSM",("0","1","2")
^SCFG: "Radio/OutputPowerReduction",("4"-"8")
^SCFG: "Serial/USB/DDD",("0","1"),("0"),(4),(4),(4),(63),(63),(4)
^SCFG: "URC/DstIfc",("mdm","app")
^SCFG: "URC/Datamode/Ringline",("off","on")
^SCFG: "URC/Ringline",("off","local","asc0","wakeup")
^SCFG: "URC/Ringline/ActiveTime",("0","1","2","keep")

OK

+CGDCONT: (1-16,101-116),"IP",,,(0),(0-4), g_str_has_prefix = 0

ModemManager[4969]: <warn>  [1457629901.718044] [mm-iface-
modem.c:3984]
load_supported_ip_families_ready(): couldn't load Supported IP
families:
'Missing +CGDCONT prefix'
ModemManager[4969]: <debug> [1457629901.719369] [mm-broadband-
modem.c:3132]
load_power_state(): loading power state...
ModemManager[4969]: <debug> [1457629901.720687] [mm-port-
serial.c:1237]
mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[4969]: <debug> [1457629901.721917] [mm-port-
serial.c:1294]
_close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[4969]: <debug> [1457629901.723342] [mm-port-serial-
at.c:440]
debug_log(): (ttyUSB2): --> 'AT+CFUN?<CR>'
ModemManager[4969]: <warn>  [1457629904.671850] [mm-iface-
modem.c:3993]
load_power_state_ready(): couldn't load Power State: 'Serial
command
timed
out'
ModemManager[4969]: <debug> [1457629904.672282] [mm-broadband-
modem.c:1290]
modem_load_unlock_required(): checking if unlock required...
ModemManager[4969]: <debug> [1457629904.672492] [mm-port-
serial.c:1237]
mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[4969]: <debug> [1457629904.672764] [mm-port-
serial.c:1294]
_close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[4969]: <debug> [1457629904.673007] [mm-port-serial-
at.c:440]
debug_log(): (ttyUSB2): --> 'AT+CPIN?<CR>'
ModemManager[4969]: <debug> [1457629904.704292] [mm-port-serial-
at.c:440]
debug_log(): (ttyUSB2): <-- '<CR><LF>+CPIN:
READY<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[4969]: <debug> [1457629904.706089] [mm-port-
serial.c:1294]
_close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4969]: <debug> [1457629904.707959] [mm-port-
serial.c:1237]
mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)




On Thu, Mar 10, 2016 at 3:06 PM, Ali Nematollahi <alirezan1 gmail
.com

wrote:



Attached is the complete start up log capture of the MM. Hope
this
helps.

Thanks Dan

On Thu, Mar 10, 2016 at 2:48 PM, Dan Williams <dcbw redhat com>
wrote:



On Thu, 2016-03-10 at 13:49 -0800, Ali Nematollahi wrote:



^SCFG query comes after CGDCONT query:

Do you see any "AT^SCFG=?" sent by ModemManager anywhere in
the
logs?

Dan



ModemManager[2738]: <debug> [946691988.680690] [mm-port-
serial-
at.c:440]
debug_log(): (ttyUSB2): --> 'AT+CGDCONT=?<CR>'
ModemManager[2738]: <debug> [946691988.699302] [mm-port-
serial-
at.c:440]
debug_log(): (ttyUSB2): <-- '<CR><LF>^SCFG:
"Audio/Loop",("0","1")<CR><LF>^SCFG: "Call/ECC",("0"-
"255")<CR><LF>^SCFG:
"Call/Speech/Codec",("0","1")<CR><LF>^SCFG:
"GPRS/Auth",("0","1","2")<CR><LF>^SCFG:
"GPRS/AutoAttach",("disabled","enabled")<CR><LF>^SCFG:
"GPRS/MaxDataRate/HSDPA",("0","1")<CR><LF>^SCFG:
"GPRS/MaxDataRate/HSUPA",("0","1")<CR><LF>^SCFG:
"Ident/Manufacturer",(25)<CR><LF>^SCFG:
"Ident/Product",(25)<CR><LF>^SCFG:
"MEopMode/Airplane",("off","on")<CR><LF>^SCFG:
"MEopMode/CregRoam",("0","1")<CR><LF>^SCFG:
"MEopMode/CFUN",("0","1")<CR><LF>^SCFG:
"MEopMode/PowerMgmt/LCI",("disabled","enabled")<CR><LF>^SCF
G:
"MEopMode/PowerMgmt/VExt",("high","low")<CR><LF>^SCFG:
"MEopMode/PwrSave",("disabled","enabled"),("0-600"),("1-
36000")<CR><LF>^SCFG:
"MEopMode/RingOnData",("on","off")<CR><LF>^SCFG:
"MEopMode/RingUrcOnCall",("on","off")<CR><LF>^SCFG:
"MEShutdown/OnIgnition",("on","off")<CR><LF>^SCFG:
"Radio/Band",("4-108","0-1")<CR><LF>^SCFG:
"Radio/NWSM",("0","1","2")<CR><LF>^SCFG:
"Radio/OutputPowerReduction",("4"-"8")<CR><LF>^SCFG:
"Serial/USB/DDD",("0","1"),("0"),(4),(4),(4),(63),(63),(4)<
CR><
LF>^SC
FG:
"URC/DstIfc",("mdm","app")<CR><LF>^SCFG:
"URC/Datamode/Ringline",("off","on")<CR><LF>^SCFG:
"URC/Ringline",("off","local","asc0","wakeup")<CR><LF>^SCFG
:
"URC/Ringline/ActiveTime",("0","1","2","keep")<CR><LF><CR><
LF>O
K<CR><
LF><CR><LF>+CGDCONT:
(1-16,101-116),"IP",,,(0),(0-4)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[2738]: <warn>  [946691988.701381] [mm-iface-
modem.c:3984]
load_supported_ip_families_ready(): couldn't load Supported
IP
families:
'Missing +CGDCONT prefix'
ModemManager[2738]: <debug> [946691988.702102] [mm-
broadband-
modem.c:3132]
load_power_state(): loading power state...
ModemManager[2738]: <debug> [946691988.702786] [mm-port-
serial.c:1237]
mm_port_serial_open(): (ttyUSB2) device open count is 3
(open)
ModemManager[2738]: <debug> [946691988.703592] [mm-port-
serial.c:1294]
_close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[2738]: <debug> [946691988.704322] [mm-port-
serial-
at.c:440]
debug_log(): (ttyUSB2): --> 'AT+CFUN?<CR>'
ModemManager[2738]: <warn>  [946691991.677255] [mm-iface-
modem.c:3993]
load_power_state_ready(): couldn't load Power State:
'Serial
command
timed
out'
ModemManager[2738]: <debug> [946691991.683414] [mm-
broadband-
modem.c:1290]
modem_load_unlock_required(): checking if unlock
required...


On Thu, Mar 10, 2016 at 1:26 PM, Dan Williams <dcbw redhat 
com>
wrote:




On Thu, 2016-03-10 at 11:00 -0800, Ali Nematollahi wrote:




Hi Dan

Perfect. I did that and I see this:


ModemManager[2757]: <debug> (ttyUSB2) device open count
is
2
(close)
ModemManager[2757]: <debug> (ttyUSB2): -->
'AT+CGDCONT=?<CR>'

What are the previous 10 lines here?  Does the modem
really
respond
to
AT+CGDCONT=? with unsolicited ^SCFG output or is that
left-
over
from a
previous AT^SCFG=? request that MM is making?

Dan




ModemManager[2757]: <debug> (ttyUSB2): <--
'<CR><LF>^SCFG:
"Audio/Loop",("0","1")<CR><LF>^SCFG: "Call/ECC",("0"-
"255")<CR><LF>^SCFG:
"Call/Speech/Codec",("0","1")<CR><LF>^SCFG:
"GPRS/Auth",("0","1","2")<CR><LF>^SCFG:
"GPRS/AutoAttach",("disabled","enabled")<CR><LF>^SCFG:
"GPRS/MaxDataRate/HSDPA",("0","1")<CR><LF>^SCFG:
"GPRS/MaxDataRate/HSUPA",("0","1")<CR><LF>^SCFG:
"Ident/Manufacturer",(25)<CR><LF>^SCFG:
"Ident/Product",(25)<CR><LF>^SCFG:
"MEopMode/Airplane",("off","on")<CR><LF>^SCFG:
"MEopMode/CregRoam",("0","1")<CR><LF>^SCFG:
"MEopMode/CFUN",("0","1")<CR><LF>^SCFG:
"MEopMode/PowerMgmt/LCI",("disabled","enabled")<CR><LF>
^SCF
G:
"MEopMode/PowerMgmt/VExt",("high","low")<CR><LF>^SCFG:
"MEopMode/PwrSave",("disabled","enabled"),("0-
600"),("1-
36000")<CR><LF>^SCFG:
"MEopMode/RingOnData",("on","off")<CR><LF>^SCFG:
"MEopMode/RingUrcOnCall",("on","off")<CR><LF>^SCFG:
"MEShutdown/OnIgnition",("on","off")<CR><LF>^SCFG:
"Radio/Band",("4-108","0-1")<CR><LF>^SCFG:
"Radio/NWSM",("0","1","2")<CR><LF>^SCFG:
"Radio/OutputPowerReduction",("4"-"8")<CR><LF>^SCFG:
"Serial/USB/DDD",("0","1"),("0"),(4),(4),(4),(63),(63),
(4)<
CR><LF

^SC

FG:
"URC/DstIfc",("mdm","app")<CR><LF>^SCFG:
"URC/Datamode/Ringline",("off","on")<CR><LF>^SCFG:
"URC/Ringline",("off","local","asc0","wakeup")<CR><LF>^
SCFG
:
"URC/Ringline/ActiveTime",("0","1","2","keep")<CR><LF><
CR><
LF>OK<
CR><
LF><CR><LF>+CGDCONT:
(1-16,101-116),"IP",,,(0),(0-
4)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[2757]: <warn>  couldn't load Supported IP
families:
'Missing
+CGDCONT prefix'
ModemManager[2757]: <debug> loading power state...
ModemManager[2757]: <debug> (ttyUSB2) device open count
is
3
(open)
ModemManager[2757]: <debug> (ttyUSB2) device open count
is
2
(close)

This is a modem that only supports IPv4.

How can I get around this problem?



On Thu, Mar 10, 2016 at 10:32 AM, Dan Williams <dcbw re
dhat
.com>
wrote:





On Thu, 2016-03-10 at 10:24 -0800, Ali Nematollahi
wrote:





Hi Thomas

Thanks! This is much better...here is the output
from
start
to
finish:
mmcli -m 0

/org/freedesktop/ModemManager1/Modem/0 (device id
'2ea06a44171a29335a8b7f781e0f9559bc24976d')
  -------------------------
  Hardware |   manufacturer: 'Cinterion'
           |          model: 'PHS8-USA'
           |       revision: 'REVISION 03.001'
           |      supported: 'gsm-umts'
           |        current: 'gsm-umts'
           |   equipment id: '351502050184415'
  -------------------------
  System   |         device:
'/sys/devices/ocp.2/47400000.usb/47401c00.usb/musb-
hdrc.1.auto/usb2/2-1'
           |        drivers: 'option1'
           |         plugin: 'Cinterion'
           |   primary port: 'ttyUSB2'
           |          ports: 'ttyUSB1 (gps),
ttyUSB2
(at),
ttyUSB3
(at)'
  -------------------------
  Numbers  |           own : '+12899368246'
  -------------------------
  Status   |           lock: 'none'
           | unlock retries: 'sim-pin (5), sim-pin2
(5), sim-
puk
(10),
sim-puk2 (10), ph-net-pin (10), ph-net-puk (32),
ph-
fsim-pin
(10),
ph-fsim-puk (32)'
           |          state: 'disabled'
           |    power state: 'unknown'
           |    access tech: 'unknown'
           | signal quality: '0' (cached)
  -------------------------
  Modes    |      supported: 'allowed: 2g;
preferred:
none
           |                  allowed: 3g;
preferred:
none
           |                  allowed: 2g, 3g;
preferred:
none'
           |        current: 'allowed: any;
preferred:
none'
  -------------------------
  Bands    |      supported: 'unknown'
           |        current: 'unknown'
  -------------------------
  IP       |      supported: 'none'

Ok, here's the problem.  Could you grab the
ModemManager
debug
output?

mmcli --set-logging=debug

and then replug the modem (if you can!) or rmmod the
modem
drivers
and
modprobe them again.  We're looking for the response
to
the
"AT+CGDCONT=?" command and why MM apparently doesn't
parse that
correctly.  That'll look something like this:

ModemManager[2768]: <debug> [1457634614.295964] [mm-
port-
serial-
at.c:459]
debug_log(): (ttyACM3): --> 'AT+CGDCONT=?<CR>'
ModemManager[2768]: <debug> [1457634614.309936] [mm-
port-
serial-
at.c:459]
debug_log(): (ttyACM3): <-- '<CR><LF>+CGDCONT:
(1-10),"IP",,,(0,1),(0,1)<CR><LF>+CGDCONT:
(1-10),"IPV6",,,(0,1),(0,1)<CR><LF>+CGDCONT:
(1-
10),"IPV4V6",,,(0,1),(0,1)<CR><LF><CR><LF>OK<CR><LF>'

which for this device results in:

  IP       |      supported: 'ipv4, ipv6, ipv4v6'

Dan





  -------------------------
  3GPP     |           imei: '351502050184415'
           |  enabled locks: 'none'
           |    operator id: 'unknown'
           |  operator name: 'unknown'
           |   subscription: 'unknown'
           |   registration: 'unknown'
  -------------------------
  SIM      |           path:
'/org/freedesktop/ModemManager1/SIM/0'

  -------------------------
  Bearers  |          paths: 'none'

root beaglebone:~# mmcli -m 0 -e
ModemManager[2687]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0:
state changed (disabled -> enabling)
ModemManager[2687]: <warn>  (ttyUSB2): port
attributes
not
fully
set
ModemManager[2687]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0:
3GPP Registration state changed (unknown ->
registering)
ModemManager[2687]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0:
3GPP Registration state changed (registering ->
home)

mmcli -m 0 --simple-connect="apn=m2minternet.apn"
ModemManager[2687]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0:
state changed (enabling -> registered)
successfully enabled the modem
root beaglebone:~#
root beaglebone:~# mmcli -m 0 --simple-
connect="apn=m2minternet.apn"
ModemManager[2687]: <info>  Simple connect
started...
ModemManager[2687]: <info>  Simple connect state
(4/8):
Wait
to
get
fully
enabled
ModemManager[2687]: <info>  Simple connect state
(5/8):
Register
ModemManager[2687]: <info>  Simple connect state
(6/8):
Bearer
ModemManager[2687]: <info>  Simple connect state
(7/8):
Connect
ModemManager[2687]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0:
state changed (registered -> connecting)
ModemManager[2687]: <warn>  (ttyUSB3): port
attributes
not
fully
set
ModemManager[2687]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0:
state changed (connecting -> connected)
ModemManager[2687]: <info>  Simple connect state
(8/8):
All
done
successfully connected the modem
root beaglebone:~# mmcli -b 0
Bearer '/org/freedesktop/ModemManager1/Bearer/0'
  -------------------------
  Status             |   connected: 'yes'
                     |   suspended: 'no'
                     |   interface: 'ttyUSB3'
                     |  IP timeout: '20'
  -------------------------
  Properties         |         apn:
'm2minternet.apn'
                     |     roaming: 'allowed'
                     |     IP type: 'none'
                     |        user: 'none'
                     |    password: 'none'
                     |      number: 'none'
                     | Rm protocol: 'unknown'
  -------------------------
  IPv4 configuration |   method: 'ppp'
                     |  address: 'unknown'
                     |   prefix: '0'
                     |  gateway: 'unknown'
                     |      DNS: none
  -------------------------
  IPv6 configuration |   method: 'unknown'
root beaglebone:~#   export G_DEBUG=fatal-criticals
root beaglebone:~# export G_DEBUG=fatal-criticals
root beaglebone:~# echo $G_DEBUG
fatal-criticals
root beaglebone:~#   gdb /usr/sbin/NetworkManager
GNU gdb (GDB) 7.4.1-debian
Copyright (C) 2012 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://
gnu.
org/li
cens
es/g
pl.html

This is free software: you are free to change and
redistribute
it.
There is NO WARRANTY, to the extent permitted by
law.  Type
"show
copying"
and "show warranty" for details.
This GDB was configured as "arm-linux-gnueabihf".
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>...
Reading symbols from
/usr/sbin/NetworkManager...done.
(gdb) run --debug
Starting program: /usr/sbin/NetworkManager --debug
[Thread debugging using libthread_db enabled]
Using host libthread_db library
"/lib/arm-linux-gnueabihf/libthread_db.so.1".
NetworkManager-Message: <info>  No config file
found or
given;
using
/etc/NetworkManager/NetworkManager.conf

[New Thread 0xb68723c0 (LWP 2720)]

(NetworkManager:2717): NetworkManager-WARNING **:
<warn>  glib-
version:
cannot handle SIGUSR1 and SIGUSR2 signals. Consider
upgrading
glib to
2.36.0 or newer
NetworkManager[2717]: <info>  NetworkManager
(version
1.1.90)
is
starting...
NetworkManager[2717]: <info>  Read config:
/etc/NetworkManager/NetworkManager.conf
[New Thread 0xb60723c0 (LWP 2721)]
[New Thread 0xb58723c0 (LWP 2722)]
NetworkManager[2717]: <info>  monitoring kernel
firmware
directory
'/lib/firmware'.
NetworkManager[2717]: <info>  dns-mgr[0x1f4c20]:
set
resolv-
conf-
mode:
default
NetworkManager[2717]: <info>  dns-mgr[0x1f4c20]:
using
resolv.conf
manager
'none'
NetworkManager[2717]: <info>  init!
NetworkManager[2717]: <info>        interface-
parser:
parsing
file
/etc/network/interfaces
NetworkManager[2717]: <info>        interface-
parser:
finished
parsing file
/etc/network/interfaces
NetworkManager[2717]: <info>  guessed connection
type
(eth0)
=
802-3-ethernet
NetworkManager[2717]:
<info>  update_connection_setting_from_if_block:
name:eth0, type:802-3-ethernet, id:Ifupdown (eth0),
uuid:
681b428f-beaf-8932-dce4-687ed5bae28e
NetworkManager[2717]: <info>  adding eth0 to
connections
NetworkManager[2717]: <info>  adding iface eth0 to
eni_ifaces
NetworkManager[2717]: <info>  guessed connection
type
(usb0)
=
802-3-ethernet
NetworkManager[2717]:
<info>  update_connection_setting_from_if_block:
name:usb0, type:802-3-ethernet, id:Ifupdown (usb0),
uuid:
3232978a-bef5-2ef2-3aa8-fdd650bb306d
NetworkManager[2717]: <info>  addresses count: 1
NetworkManager[2717]: <info>  No dns-nameserver
configured in
/etc/network/interfaces
NetworkManager[2717]: <info>  adding usb0 to
connections
NetworkManager[2717]: <info>  adding iface usb0 to
eni_ifaces
NetworkManager[2717]: <info>  autoconnect
NetworkManager[2717]: <info>  management mode:
unmanaged
NetworkManager[2717]: <info>  devices added (path:
/sys/devices/ocp.2/481cc000.d_can/net/can0, iface:
can0)
NetworkManager[2717]: <info>  device added (path:
/sys/devices/ocp.2/481cc000.d_can/net/can0, iface:
can0): no
ifupdown
configuration found.
NetworkManager[2717]: <info>  devices added (path:
/sys/devices/ocp.2/481d0000.d_can/net/can1, iface:
can1)
NetworkManager[2717]: <info>  device added (path:
/sys/devices/ocp.2/481d0000.d_can/net/can1, iface:
can1): no
ifupdown
configuration found.
NetworkManager[2717]: <info>  devices added (path:
/sys/devices/ocp.2/4a100000.ethernet/net/eth0,
iface:
eth0)
NetworkManager[2717]: <info>  locking wired
connection
setting
NetworkManager[2717]: <info>  devices added (path:
/sys/devices/virtual/net/lo, iface: lo)
NetworkManager[2717]: <info>  device added (path:
/sys/devices/virtual/net/lo, iface: lo): no
ifupdown
configuration
found.
NetworkManager[2717]: <info>  end _init.
NetworkManager[2717]: <info>  Loaded settings
plugin
ifupdown:
(C)
2008
Canonical Ltd.  To report bugs please use the
NetworkManager
mailing
list.
(/usr/lib/NetworkManager/libnm-settings-plugin-
ifupdown.so)
NetworkManager[2717]: <info>  Loaded settings
plugin
iBFT:
(c)
2014
Red
Hat, Inc.  To report bugs please use the
NetworkManager
mailing
list.
(/usr/lib/NetworkManager/libnm-settings-plugin-
ibft.so)
NetworkManager[2717]: <info>  Loaded settings
plugin
keyfile:
(c)
2007 -
2015 Red Hat, Inc.  To report bugs please use the
NetworkManager
mailing
list.
NetworkManager[2717]: <info>  (2093568) ...
get_connections.
NetworkManager[2717]: <info>  (2093568) ...
get_connections
(managed=false): return empty list.
NetworkManager[2717]: <info>  keyfile: new
connection
/etc/NetworkManager/system-connections/ali
(d14ba871-2179-4e8e-82bc-bb053c9c6ee9,"ali")
NetworkManager[2717]: <info>  get unmanaged devices
count: 1
NetworkManager[2717]: <info>  hostname: couldn't
get
property
from
hostnamed
NetworkManager[2717]: <info>  WiFi enabled by radio
killswitch;
enabled by
state file
NetworkManager[2717]: <info>  WWAN enabled by radio
killswitch;
enabled by
state file
NetworkManager[2717]: <info>  Networking is enabled
by
state
file
NetworkManager[2717]: <info>  Loaded device plugin:
NMVxlanFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMVlanFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMVethFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMTunFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMMacvlanFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMIPTunnelFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMInfinibandFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMEthernetFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMBridgeFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMBondFactory
(internal)
NetworkManager[2717]: <info>  Loaded device plugin:
NMWifiFactory
(/usr/lib/NetworkManager/libnm-device-plugin-
wifi.so)
NetworkManager[2717]: <info>  Loaded device plugin:
NMBluezManager
(/usr/lib/NetworkManager/libnm-device-plugin-
bluetooth.so)
NetworkManager[2717]: <info>  Loaded device plugin:
NMWwanFactory
(/usr/lib/NetworkManager/libnm-device-plugin-
wwan.so)
NetworkManager[2717]: <info>  Loaded device plugin:
NMAtmManager
(/usr/lib/NetworkManager/libnm-device-plugin-
adsl.so)
NetworkManager[2717]: <warn>  platform-linux:
kernel
support
for
IFLA_INET6_ADDR_GEN_MODE failed to detect; assume
no
support
NetworkManager[2717]: <info>  (can1): new Generic
device
(/org/freedesktop/NetworkManager/Devices/0)
NetworkManager[2717]: <info>  (can0): new Generic
device
(/org/freedesktop/NetworkManager/Devices/1)
NetworkManager[2717]: <info>  (eth0): link
connected
NetworkManager[2717]: <info>  (eth0): new Ethernet
device
(/org/freedesktop/NetworkManager/Devices/2)
NetworkManager[2717]: <info>  (lo): link connected
NetworkManager[2717]: <info>  (lo): new Generic
device
(/org/freedesktop/NetworkManager/Devices/3)
NetworkManager[2717]: <info>  startup complete
[New Thread 0xb4e0d3c0 (LWP 2724)]
NetworkManager[2717]: <info>  ModemManager
available in
the
bus
NetworkManager[2717]: <info>  (ttyUSB2): new
Broadband
device
(/org/freedesktop/NetworkManager/Devices/4)
NetworkManager[2717]: <info>  (ttyUSB2): device
state
change:
unmanaged ->
unavailable (reason 'managed') [10 20 2]
NetworkManager[2717]: <info>  (ttyUSB2): modem
state
'connected'
NetworkManager[2717]: <info>  (ttyUSB2): device
state
change:
unavailable
-> disconnected (reason 'none') [20 30 0]
NetworkManager[2717]: <info>  Auto-activating
connection
'ali'.
**
NetworkManager:ERROR:nm-exported-
object.c:293:nm_exported_object_class_add_interface
:
assertion failed: (object_property != NULL)

Program received signal SIGABRT, Aborted.
0xb6ab8f96 in ?? () from /lib/arm-linux-
gnueabihf/libc.so.6
(gdb) bt
#0  0xb6ab8f96 in ?? () from /lib/arm-linux-
gnueabihf/libc.so.6
#1  0xb6ac6f6a in raise () from /lib/arm-linux-
gnueabihf/libc.so.6
#2  0xb6ac9408 in abort () from /lib/arm-linux-
gnueabihf/libc.so.6
#3  0xb6cb3d6a in g_assertion_message ()
   from /lib/arm-linux-gnueabihf/libglib-2.0.so.0
#4  0xb6cb4142 in g_assertion_message_expr ()
   from /lib/arm-linux-gnueabihf/libglib-2.0.so.0
#5  0x000b19fe in
nm_exported_object_class_add_interface (
    object_class=0xb19ff,
dbus_skeleton_type=2435888)
    at nm-exported-object.c:293
#6  0xb6d46634 in g_type_class_ref ()
   from /usr/lib/arm-linux-gnueabihf/libgobject-
2.0.so.0
#7  0xb6d466ce in g_type_class_ref ()
   from /usr/lib/arm-linux-gnueabihf/libgobject-
2.0.so.0
#8  0xb6d35972 in g_object_new_valist ()
   from /usr/lib/arm-linux-gnueabihf/libgobject-
2.0.so.0
#9  0xb6d35b88 in g_object_new ()
   from /usr/lib/arm-linux-gnueabihf/libgobject-
2.0.so.0
#10 0x0009fa36 in nm_act_request_new
(settings_connection=<optimized
out>,
    specific_object=0x0, subject=0x233cc0,
device=0x24e018)
    at nm-activation-request.c:487
#11 0x000c4854 in nm_manager_activate_connection
(self=0x1e2020,
    connection=0x20a008, specific_object=0x0,
device=0x24e018,
---Type <return> to continue, or q <return> to
quit---
    subject=0x233cc0, error=0xbefffb14) at nm-
manager.c:3112
#12 0x000c70b6 in auto_activate_device
(user_data=0x23dcd8)
at
nm-policy.c:694
#13 0xb6c9ca02 in ?? () from /lib/arm-linux-
gnueabihf/libglib-
2.0.so.0
#14 0xb6c9ca02 in ?? () from /lib/arm-linux-
gnueabihf/libglib-
2.0.so.0
Backtrace stopped: previous frame identical to this
frame
(corrupt
stack?)
(gdb) bt full
#0  0xb6ab8f96 in ?? () from /lib/arm-linux-
gnueabihf/libc.so.6
No symbol table info available.
#1  0xb6ac6f6a in raise () from /lib/arm-linux-
gnueabihf/libc.so.6
No symbol table info available.
#2  0xb6ac9408 in abort () from /lib/arm-linux-
gnueabihf/libc.so.6
No symbol table info available.
#3  0xb6cb3d6a in g_assertion_message ()
   from /lib/arm-linux-gnueabihf/libglib-2.0.so.0
No symbol table info available.
#4  0xb6cb4142 in g_assertion_message_expr ()
   from /lib/arm-linux-gnueabihf/libglib-2.0.so.0
No symbol table info available.
#5  0x000b19fe in
nm_exported_object_class_add_interface (
    object_class=0xb19ff,
dbus_skeleton_type=2435888)
    at nm-exported-object.c:293
        hyphen_name = 0xb6d23000 "\350\336\v"
        classinfo = 0xb6cc750f
        method = {dbus_skeleton_type = 0,
method_name =
0x0,
impl
=
0x4}
        ap = {__ap = 0xbefff974}
        method_name = <optimized out>
        impl = <optimized out>
        interfaces = 0x0
        n_interfaces = <optimized out>
---Type <return> to continue, or q <return> to
quit---
        n_signals = <optimized out>
        n_method_signals = <optimized out>
        object_signal_id = <optimized out>
        query = {signal_id = 0, signal_name = 0x0,
itype =
2434192,
          signal_flags = 1829872, return_type = 0,
n_params =
655613,
          param_types = 0x0}
        i = <optimized out>
        s = <optimized out>
        dbus_object_class = 0x24c730
        dbus_properties = 0x187340
        object_property = <optimized out>
        n_dbus_properties = 0
        __PRETTY_FUNCTION__ =
"nm_exported_object_class_add_interface"
#6  0xb6d46634 in g_type_class_ref ()
   from /usr/lib/arm-linux-gnueabihf/libgobject-
2.0.so.0
No symbol table info available.
#7  0xb6d466ce in g_type_class_ref ()
   from /usr/lib/arm-linux-gnueabihf/libgobject-
2.0.so.0
No symbol table info available.
#8  0xb6d35972 in g_object_new_valist ()
   from /usr/lib/arm-linux-gnueabihf/libgobject-
2.0.so.0
No symbol table info available.
#9  0xb6d35b88 in g_object_new ()
---Type <return> to continue, or q <return> to
quit---
   from /usr/lib/arm-linux-gnueabihf/libgobject-
2.0.so.0
No symbol table info available.
#10 0x0009fa36 in nm_act_request_new
(settings_connection=<optimized
out>,
    specific_object=0x0, subject=0x233cc0,
device=0x24e018)
    at nm-activation-request.c:487
        __PRETTY_FUNCTION__ = "nm_act_request_new"
#11 0x000c4854 in nm_manager_activate_connection
(self=0x1e2020,
    connection=0x20a008, specific_object=0x0,
device=0x24e018,
    subject=0x233cc0, error=0xbefffb14) at nm-
manager.c:3112
        priv = 0x1e2090
        active = <optimized out>
        error_desc = 0x0
        iter = 0x0
        __PRETTY_FUNCTION__ =
"nm_manager_activate_connection"
#12 0x000c70b6 in auto_activate_device
(user_data=0x23dcd8)
at
nm-policy.c:694
        error = 0x0
        subject = 0x233cc0
        data = 0x23dcd8
        policy = <optimized out>
        priv = <optimized out>
        best_connection = 0x20a008
        specific_object = 0x0
        connections = 0x1dddc8
---Type <return> to continue, or q <return> to
quit---
        connection_list = 0x250420
        i = <optimized out>
        __PRETTY_FUNCTION__ =
"auto_activate_device"
#13 0xb6c9ca02 in ?? () from /lib/arm-linux-
gnueabihf/libglib-
2.0.so.0
No symbol table info available.
#14 0xb6c9ca02 in ?? () from /lib/arm-linux-
gnueabihf/libglib-
2.0.so.0
No symbol table info available.
Backtrace stopped: previous frame identical to this
frame
(corrupt
stack?)
(gdb)


On Thu, Mar 10, 2016 at 2:39 AM, Thomas Haller
<thaller
@redha
t.co
m>
wrote:






On Wed, 2016-03-09 at 14:35 -0800, Ali
Nematollahi
wrote:






I'm not concerned about IPv6 at all.
I'm running Kernel 3.12

Here is the backtrace:
root beaglebone:~# gdb /usr/sbin/NetworkManager
GNU gdb (GDB) 7.4.1-debian
Copyright (C) 2012 Free Software Foundation,
Inc.
License GPLv3+: GNU GPL version 3 or later
<http://
gnu.or
g/li
cens
es/g
pl.html>
This is free software: you are free to change
and
redistribute
it.
There is NO WARRANTY, to the extent permitted
by
law.  Type
"show
copying"
and "show warranty" for details.
This GDB was configured as "arm-linux-
gnueabihf".
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>...
Reading symbols from
/usr/sbin/NetworkManager...done.
(gdb) run --debug --g-fatal-warnings
Starting program: /usr/sbin/NetworkManager --
debug
--g-
fatal-
warnings
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/arm-
linux-
gnueabihf/libthread_db.so.1".
NetworkManager-Message: <info>  No config file
found or
given;
using
/etc/NetworkManager/NetworkManager.conf

[New Thread 0xb686b3c0 (LWP 2821)]

(NetworkManager:2818): NetworkManager-WARNING
**:
<warn>  glib-
version: cannot handle SIGUSR1 and SIGUSR2
signals.
Consider
upgrading glib to 2.36.0 or newer

Hmpf, seems you cannot use --g-fatal-warnings
because
then
NM
dumps
core on this warning. This would be fixed now on
master

https://cgit.freedesktop.org/NetworkManager/Netwo
rkMa
nager/
comm
it/?
id=b49322b568725c8c92c0732b14cc726748ec57d3


Can you try again without --g-fatal-warnings:

  export G_DEBUG=fatal-criticals
  gdb /usr/sbin/NetworkManager
  > run --debug


Thomas

_______________________________________________
networkmanager-list mailing list
networkmanager-list gnome org
https://mail.gnome.org/mailman/listinfo/networkmana
ger-
list

Follow-Ups:
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi

References:
- NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Thomas Haller
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Thomas Haller
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Dan Williams
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Dan Williams
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Dan Williams
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Dan Williams
- Re: NetworkManager 1.1.90 : nm-exported-object.c:293:nm_exported_object_class_add_interface: assertion failed: (object_property != NULL)
  - From: Ali Nematollahi

[Date Prev][Date Next] [Thread Prev][Thread Next] [Thread Index] [Date Index] [Author Index]