MC7354 Connection time out

IoT Modules MC/EM Series
1

Hi:

I have MC7354 that has a test SIM installed. I am trying to connect it to a R&S CMW500 cellular tester. it is attached to the cell and also starting connection request, everything seems to be fine from the log, but MC7354 indicated connection timed out after about 1 minutes, here is the trace segment where time out happened, the detail is in the attachment,
May 14 15:08:08: received cell signal and attached to the cell
May 14 15:08:19: activate connection,
…
May 14 15:08:19 UA-CLM-LAB NetworkManager[1213]: [1589483299.4391] device[0x55ec7357ec50] (cdc-wdm0): add_pending_action (2): ‘recheck-available’  why adding this?
May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Defaulting to use static IP method  using static IP?
May 14 15:08:19 UA-CLM-LAB NetworkManager[1213]: [1589483299.4392] device[0x55ec7357ec50] (cdc-wdm0): remove_pending_action (1): ‘recheck-available’  then remove, not sure why?
…
May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Starting IPv4 connection…
…

May 14 15:09:04 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] sent message…#012<<<<<< RAW:#012<<<<<< length = 18#012<<<<<< data = 01:11:00:00:01:09:00:22:00:02:00:05:00:01:02:00:21:00
May 14 15:09:04 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] sent generic request (translated)…#012<<<<<< QMUX:#012<<<<<< length = 17#012<<<<<< flags = 0x00#012<<<<<< service = “wds”#012<<<<<< client = 9#012<<<<<< QMI:#012<<<<<< flags = “none”#012<<<<<< transaction = 34#012<<<<<< tlv_length = 5#012<<<<<< message = “Abort” (0x0002)#012<<<<<< TLV:#012<<<<<< type = “Transaction ID” (0x01)#012<<<<<< length = 2#012<<<<<< value = 21:00#012<<<<<< translated = 33
May 14 15:09:04 UA-CLM-LAB ModemManager[1233]: Couldn’t connect bearer ‘/org/freedesktop/ModemManager1/Bearer/0’: ‘Transaction timed out’
May 14 15:09:04 UA-CLM-LAB ModemManager[1233]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
May 14 15:09:04 UA-CLM-LAB ModemManager[1233]: Couldn’t connect bearer: ‘Transaction timed out’  send disconnect message to cell indicated time out, not sure what modem is waiting for?

cannot upload log file as new user
Thanks
Bill

2

Hi billy.he

Could you please try MC7354 with SIM live network?
If it works well with the live network, this problem may come from CMW500.
Please check the configuration on CMW500. You can try unchecking inactivity timer on CMW500.(Configure\Connection\Keep RRC Connection -> uncheck Inactivity Timer)

I have attached the user guide CMW500 for your reference.
Notes_CMW500_Data_Application_Unit_R02.00.pptx (2.5 MB)
http://www.vogold.com.cn/upfile/cmw500pdf/CMW500_UserManual_V3-0-14.pdf

Please help to mark “Solution” if your question is answered.

3

@billy.he

There is no specific timeout for a connection in the unit, it gets all of its timers/out from the network (unless it has to apply the defaults from 3GPP in the absence of network information so the timeout will either be coming from the MC7354’s host system or the system information being broadcast by the CMW.

Is the networkmanager/modemmanager from the CMW management tool suite?

Regards

Matt

4

Matt:

The networkmanager/modemmanager are tow utilities that are running on Ubuntu Linux OS for the MC7354.

The SIM card that I am using is R&S Z06 suggested by R&S. the time out is coming from the modem side (MC7354), I don’t know what it is waiting for?

Also, the modem initiated connection with static IP (see yellow highlight, also there are two actions happened (see green highlight), I don’t know what are these, it seems ok, as it was added then removed:

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Simple connect started…

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: PIN: unspecified

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Operator ID: unspecified

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Allowed roaming: yes

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: APN: RS_Apn

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: IP family: ipv4

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Allowed authentication: none, pap, chap, mschap, mschapv2, eap

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: User: unspecified

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Password: unspecified

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Simple connect state (4/8): Wait to get fully enabled

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Simple connect state (5/8): Register

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Already registered in network ‘00101’, automatic registration not launched…

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Simple connect state (6/8): Bearer

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Using already existing bearer at ‘/org/freedesktop/ModemManager1/Bearer/0’…

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Simple connect state (7/8): Connect

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Connecting bearer ‘/org/freedesktop/ModemManager1/Bearer/0’

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)

May 14 15:08:19 UA-CLM-LAB NetworkManager[1213]: [1589483299.4391] modem[“cdc-wdm0”]: modem state changed, ‘registered’ --> ‘connecting’ (reason: user-requested)

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Launching connection with QMI port (usb/cdc-wdm0) and data port (net/wwan0)

May 14 15:08:19 UA-CLM-LAB NetworkManager[1213]: [1589483299.4391] device[0x55ec7357ec50] (cdc-wdm0): add_pending_action (2): ‘recheck-available’

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Defaulting to use static IP method

May 14 15:08:19 UA-CLM-LAB NetworkManager[1213]: [1589483299.4392] device[0x55ec7357ec50] (cdc-wdm0): remove_pending_action (1): ‘recheck-available’

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Running IPv4 connection setup

May 14 15:08:19 UA-CLM-LAB ModemManager[1233]: Setting default IP family to: IPv4

The R&S returned 3GPP information as following, not sure what information would trig the timeout?:

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received message…#012<<<<<< RAW:#012<<<<<< length = 66#012<<<<<< data = 01:41:00:80:03:01:04:00:00:24:00:35:00:29:05:00:01:00:01:00:00:22:05:00:01:02:00:01:00:15:03:00:01:08:01:12:0B:00:01:00:01:00:06:30:30:31:2D:30:31:11:01:00:00:10:01:00:01:01:06:00:02:02:02:02:01:08

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received generic indication (translated)…#012<<<<<< QMUX:#012<<<<<< length = 65#012<<<<<< flags = 0x80#012<<<<<< service = “nas”#012<<<<<< client = 1#012<<<<<< QMI:#012<<<<<< flags = “indication”#012<<<<<< transaction = 0#012<<<<<< tlv_length = 53#012<<<<<< message = “Serving System” (0x0024)#012<<<<<< TLV:#012<<<<<< type = “MNC PCS Digit Include Status” (0x29)#012<<<<<< length = 5#012<<<<<< value = 01:00:01:00:00#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ includes_pcs_digit = ‘no’ ]#012<<<<<< TLV:#012<<<<<< type = “Detailed Service Status” (0x22)#012<<<<<< length = 5#012<<<<<< value = 01:02:00:01:00#012<<<<<< translated = [ status = ‘limited’ capability = ‘ps’ hdr_status = ‘none’ hdr_hybrid = ‘yes’ forbidden = ‘no’ ]#012<<<<<< TLV:#012<<<<<< type = “Roaming Indicator List” (0x15)#012<<<<<< length = 3#012<<<<<< value = 01:08:01#012<<<<<< translated = { [0] = '[ radio_interface = ‘lte’ roaming_indicator = ‘off’ ] '}#012<<<<<< TLV:#012<<<<<< type = “Current PLMN” (0x12)#012<<<<<< length = 11#012<<<<<< value = 01:00:01:00:06:30:30:31:2D:30:31#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ description = ‘001-01’ ]#012<<<<<< TLV:#012<<<<<< type = “Data Service Capability” (0x11)#012<<<<<< length = 1#012<<<<<< value = 00#012<<<<<< translated = {}#012<<<<<< TLV:#012<<<<<< type = “Roaming Indicator” (0x10)#012<<<<<< length = 1#012<<<<<< value = 01#012<<<<<< translated = off#012<<<<<< TLV:#012<<<<<< type = “Serving System” (0x01)#012<<<<<< length = 6#012<<<<<< value = 02:02:02:02:01:08#012<<<<<< translated = [ registration_state = ‘not-registered-searching’ cs_attach_state = ‘detached’ ps_attach_state = ‘detached’ selected_network = ‘3gpp’ radio_interfaces = '{ [0] = 'lte ‘}’ ]

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: Processing 3GPP info…

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received message…#012<<<<<< RAW:#012<<<<<< length = 56#012<<<<<< data = 01:37:00:80:03:01:04:00:00:24:00:2B:00:29:05:00:01:00:01:00:00:22:05:00:03:03:00:01:00:12:0B:00:01:00:01:00:06:30:30:31:2D:30:31:11:01:00:00:01:06:00:02:02:02:02:01:08

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received generic indication (translated)…#012<<<<<< QMUX:#012<<<<<< length = 55#012<<<<<< flags = 0x80#012<<<<<< service = “nas”#012<<<<<< client = 1#012<<<<<< QMI:#012<<<<<< flags = “indication”#012<<<<<< transaction = 0#012<<<<<< tlv_length = 43#012<<<<<< message = “Serving System” (0x0024)#012<<<<<< TLV:#012<<<<<< type = “MNC PCS Digit Include Status” (0x29)#012<<<<<< length = 5#012<<<<<< value = 01:00:01:00:00#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ includes_pcs_digit = ‘no’ ]#012<<<<<< TLV:#012<<<<<< type = “Detailed Service Status” (0x22)#012<<<<<< length = 5#012<<<<<< value = 03:03:00:01:00#012<<<<<< translated = [ status = ‘limited-regional’ capability = ‘cs-ps’ hdr_status = ‘none’ hdr_hybrid = ‘yes’ forbidden = ‘no’ ]#012<<<<<< TLV:#012<<<<<< type = “Current PLMN” (0x12)#012<<<<<< length = 11#012<<<<<< value = 01:00:01:00:06:30:30:31:2D:30:31#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ description = ‘001-01’ ]#012<<<<<< TLV:#012<<<<<< type = “Data Service Capability” (0x11)#012<<<<<< length = 1#012<<<<<< value = 00#012<<<<<< translated = {}#012<<<<<< TLV:#012<<<<<< type = “Serving System” (0x01)#012<<<<<< length = 6#012<<<<<< value = 02:02:02:02:01:08#012<<<<<< translated = [ registration_state = ‘not-registered-searching’ cs_attach_state = ‘detached’ ps_attach_state = ‘detached’ selected_network = ‘3gpp’ radio_interfaces = '{ [0] = 'lte ‘}’ ]

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: Processing 3GPP info…

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: (ttyUSB2): <-- ‘+CIEV: 3,1’

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: (ttyUSB2): <-- ‘+CIEV: 2,1’

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: (ttyUSB2): <-- ‘+CIEV: 6,1’

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: (ttyUSB2): <-- ‘+CREG: 1,“FFFE”,“00000100”,7+CGREG: 1,“FFFE”,“00000100”,7+CEREG: 1’

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received message…#012<<<<<< RAW:#012<<<<<< length = 48#012<<<<<< data = 01:2F:00:80:03:01:04:00:00:24:00:23:00:29:05:00:01:00:01:00:00:12:0B:00:01:00:01:00:06:30:30:31:2D:30:31:11:01:00:00:01:06:00:02:02:02:02:01:08

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received generic indication (translated)…#012<<<<<< QMUX:#012<<<<<< length = 47#012<<<<<< flags = 0x80#012<<<<<< service = “nas”#012<<<<<< client = 1#012<<<<<< QMI:#012<<<<<< flags = “indication”#012<<<<<< transaction = 0#012<<<<<< tlv_length = 35#012<<<<<< message = “Serving System” (0x0024)#012<<<<<< TLV:#012<<<<<< type = “MNC PCS Digit Include Status” (0x29)#012<<<<<< length = 5#012<<<<<< value = 01:00:01:00:00#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ includes_pcs_digit = ‘no’ ]#012<<<<<< TLV:#012<<<<<< type = “Current PLMN” (0x12)#012<<<<<< length = 11#012<<<<<< value = 01:00:01:00:06:30:30:31:2D:30:31#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ description = ‘001-01’ ]#012<<<<<< TLV:#012<<<<<< type = “Data Service Capability” (0x11)#012<<<<<< length = 1#012<<<<<< value = 00#012<<<<<< translated = {}#012<<<<<< TLV:#012<<<<<< type = “Serving System” (0x01)#012<<<<<< length = 6#012<<<<<< value = 02:02:02:02:01:08#012<<<<<< translated = [ registration_state = ‘not-registered-searching’ cs_attach_state = ‘detached’ ps_attach_state = ‘detached’ selected_network = ‘3gpp’ radio_interfaces = '{ [0] = 'lte ‘}’ ]

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: Processing 3GPP info…

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received message…#012<<<<<< RAW:#012<<<<<< length = 74#012<<<<<< data = 01:49:00:80:03:01:04:00:00:24:00:3D:00:29:05:00:01:00:01:00:00:25:02:00:01:00:22:05:00:02:03:00:01:00:1E:04:00:00:01:00:00:1D:02:00:FE:FF:12:0B:00:01:00:01:00:06:30:30:31:2D:30:31:11:02:00:01:0B:01:06:00:01:01:01:02:01:08

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received generic indication (translated)…#012<<<<<< QMUX:#012<<<<<< length = 73#012<<<<<< flags = 0x80#012<<<<<< service = “nas”#012<<<<<< client = 1#012<<<<<< QMI:#012<<<<<< flags = “indication”#012<<<<<< transaction = 0#012<<<<<< tlv_length = 61#012<<<<<< message = “Serving System” (0x0024)#012<<<<<< TLV:#012<<<<<< type = “MNC PCS Digit Include Status” (0x29)#012<<<<<< length = 5#012<<<<<< value = 01:00:01:00:00#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ includes_pcs_digit = ‘no’ ]#012<<<<<< TLV:#012<<<<<< type = “LTE TAC” (0x25)#012<<<<<< length = 2#012<<<<<< value = 01:00#012<<<<<< translated = 1#012<<<<<< TLV:#012<<<<<< type = “Detailed Service Status” (0x22)#012<<<<<< length = 5#012<<<<<< value = 02:03:00:01:00#012<<<<<< translated = [ status = ‘available’ capability = ‘cs-ps’ hdr_status = ‘none’ hdr_hybrid = ‘yes’ forbidden = ‘no’ ]#012<<<<<< TLV:#012<<<<<< type = “CID 3GPP” (0x1e)#012<<<<<< length = 4#012<<<<<< value = 00:01:00:00#012<<<<<< translated = 256#012<<<<<< TLV:#012<<<<<< type = “LAC 3GPP” (0x1d)#012<<<<<< length = 2#012<<<<<< value = FE:FF#012<<<<<< translated = 65534#012<<<<<< TLV:#012<<<<<< type = “Current PLMN” (0x12)#012<<<<<< length = 11#012<<<<<< value = 01:00:01:00:06:30:30:31:2D:30:31#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ description = ‘001-01’ ]#012<<<<<< TLV:#012<<<<<< type = “Data Service Capability” (0x11)#012<<<<<< length = 2#012<<<<<< value = 01:0B#012<<<<<< translated = { [0] = 'lte '}#012<<<<<< TLV:#012<<<<<< type = “Serving System” (0x01)#012<<<<<< length = 6#012<<<<<< value = 01:01:01:02:01:08#012<<<<<< translated = [ registration_state = ‘registered’ cs_attach_state = ‘attached’ ps_attach_state = ‘attached’ selected_network = ‘3gpp’ radio_interfaces = '{ [0] = 'lte ‘}’ ]

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: Processing 3GPP info…

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received message…#012<<<<<< RAW:#012<<<<<< length = 53#012<<<<<< data = 01:34:00:80:03:01:04:00:00:24:00:28:00:29:05:00:01:00:01:00:00:19:01:00:01:12:0B:00:01:00:01:00:06:30:30:31:2D:30:31:11:02:00:01:0B:01:06:00:01:01:01:02:01:08

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: [/dev/cdc-wdm0] received generic indication (translated)…#012<<<<<< QMUX:#012<<<<<< length = 52#012<<<<<< flags = 0x80#012<<<<<< service = “nas”#012<<<<<< client = 1#012<<<<<< QMI:#012<<<<<< flags = “indication”#012<<<<<< transaction = 0#012<<<<<< tlv_length = 40#012<<<<<< message = “Serving System” (0x0024)#012<<<<<< TLV:#012<<<<<< type = “MNC PCS Digit Include Status” (0x29)#012<<<<<< length = 5#012<<<<<< value = 01:00:01:00:00#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ includes_pcs_digit = ‘no’ ]#012<<<<<< TLV:#012<<<<<< type = “PLMN Name Flag 3GPP” (0x19)#012<<<<<< length = 1#012<<<<<< value = 01#012<<<<<< translated = yes#012<<<<<< TLV:#012<<<<<< type = “Current PLMN” (0x12)#012<<<<<< length = 11#012<<<<<< value = 01:00:01:00:06:30:30:31:2D:30:31#012<<<<<< translated = [ mcc = ‘1’ mnc = ‘1’ description = ‘001-01’ ]#012<<<<<< TLV:#012<<<<<< type = “Data Service Capability” (0x11)#012<<<<<< length = 2#012<<<<<< value = 01:0B#012<<<<<< translated = { [0] = 'lte '}#012<<<<<< TLV:#012<<<<<< type = “Serving System” (0x01)#012<<<<<< length = 6#012<<<<<< value = 01:01:01:02:01:08#012<<<<<< translated = [ registration_state = ‘registered’ cs_attach_state = ‘attached’ ps_attach_state = ‘attached’ selected_network = ‘3gpp’ radio_interfaces = '{ [0] = 'lte ‘}’ ]

May 14 15:08:08 UA-CLM-LAB ModemManager[1233]: Processing 3GPP info…

Thanks

Bill

cellular.txt (40.1 KB)

5

@billy.he

I would strong recommend turning the network and modem manager on the host system off as depending on how they are configured they may well activate/deactivate/modify/open/close connections at will.

My previous statement stands, there are no settings on the unit that will cause the unit to deactivate a session autonomously and it would need to have been explicitly commanded to do so and the network/modem manager are the prime suspects to do this.

To test this out I would do the following

  • Turn the managers off (sudo stop modemmanager and sudo stop network manager)
  • Make sure no application on the host system is trying to access any network connections.
  • Power the MC7354 up and allow it to attach to the CMW.
  • Send the AT command at!scact=1,1 to it.
  • From the host CLI send ‘dhclient xxxx’ where xxxx is the interface the DHCP needs to go through to populate the interface (it will probably be WWAN0 or something like that).
  • Once populated leave it, I am not expecting it to be disconnected which will show it came from the host system.

If it does then I suggest you post the entire syslog.

Regards

Matt