Failed to find primary AT port
Dan Williams
dcbw at redhat.com
Fri Mar 31 22:14:46 UTC 2017
On Fri, 2017-03-31 at 21:09 +0000, Jan Graczyk wrote:
> Hello Dan,
>
> There is no problem with connecting to this modem (mini PCIe Quectel
> UC20 modem module) from kermit and sending AT commands. However when
> I issue the following commands:
>
> mmcli -m 0 --simple-disconnect
> mmcli -m 0 --simple-connect="pin=1234,apn=internet"
>
> the ModemManager sends debug message "Failed to find primary AT port"
> to Linux console. However if I will power down and power up the
> development board the ModemManager starts running properly. The
> problem only happen when I issue the above disconnect and then
> connect command.
The UC20 should be using QMI, not AT ports. qmi_wwan support for the
UC20 has been there since 2013. But to use it, you need to:
1) ensure qmi_wwan has been built for your kernel
2) ensure you build ModemManager with libqmi support (--with-qmi=yes)
This will solve both your problems, most likely.
Dan
> Best Regards,
>
> Jan Graczyk
>
> -----Original Message-----
> From: Dan Williams [mailto:dcbw at redhat.com]
> Sent: Friday, March 31, 2017 1:51 PM
> To: Jan Graczyk <JanG at nytec.com>; modemmanager-devel at lists.freedeskto
> p.org
> Subject: Re: Failed to find primary AT port
>
> On Fri, 2017-03-31 at 20:35 +0000, Jan Graczyk wrote:
> > Hello All,
> >
> > I have a problem with ModemManager failing to find primary AT port.
> > Her is the ModemManager debug log:
>
> What kind of modem is this?
>
> Does it require a specific baud rate and/or serial settings?
>
> Can you talk to the modem with 'minicom' on that port when
> ModemManager isn't running?
>
> Dan
>
> > root at linaro-alip:~# ModemManager --debug& [1] 1574 root at linaro-alip
> > :~#
> > ModemManager[1574]: <info> [1490989871.011911] [main.c:140]
> > main():
> > ModemManager (version 1.4.0) starting in system bus...
> > ModemManager[1574]: <debug> [1490989871.035558] [main.c:65]
> > bus_acquired_cb(): Bus acquired, creating manager...
> > ModemManager[1574]: <debug> [1490989871.047317] [mm-plugin-
> > manager.c:852] load_plugins(): Looking for plugins in
> > '/usr/lib/arm-
> > linux-gnueabihf/ModemManager'
> > ModemManager[1574]: <debug> [1490989871.049618] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Option'
> > ModemManager[1574]: <debug> [1490989871.051062] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE'
> > ModemManager[1574]: <debug> [1490989871.052389] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Linktop'
> > ModemManager[1574]: <debug> [1490989871.053841] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Samsung'
> > ModemManager[1574]: <debug> [1490989871.055357] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Sierra'
> > ModemManager[1574]: <debug> [1490989871.056738] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Gobi'
> > ModemManager[1574]: <debug> [1490989871.058087] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'X22X'
> > ModemManager[1574]: <debug> [1490989871.059471] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'MTK'
> > ModemManager[1574]: <debug> [1490989871.060811] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'AnyDATA'
> > ModemManager[1574]: <debug> [1490989871.062214] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Pantech'
> > ModemManager[1574]: <debug> [1490989871.063564] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Novatel'
> > ModemManager[1574]: <debug> [1490989871.065070] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Nokia'
> > ModemManager[1574]: <debug> [1490989871.066426] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Motorola'
> > ModemManager[1574]: <debug> [1490989871.067906] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Huawei'
> > ModemManager[1574]: <debug> [1490989871.069276] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'SimTech'
> > ModemManager[1574]: <debug> [1490989871.070701] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed'
> > ModemManager[1574]: <debug> [1490989871.072078] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Generic'
> > ModemManager[1574]: <debug> [1490989871.073583] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Iridium'
> > ModemManager[1574]: <debug> [1490989871.075071] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Altair LTE'
> > ModemManager[1574]: <debug> [1490989871.076609] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Cinterion'
> > ModemManager[1574]: <debug> [1490989871.078085] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Via CBP7'
> > ModemManager[1574]: <debug> [1490989871.079495] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Telit'
> > ModemManager[1574]: <debug> [1490989871.081009] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'ZTE'
> > ModemManager[1574]: <debug> [1490989871.082448] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Wavecom'
> > ModemManager[1574]: <debug> [1490989871.083927] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Longcheer'
> > ModemManager[1574]: <debug> [1490989871.085477] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)'
> > ModemManager[1574]: <debug> [1490989871.086972] [mm-plugin-
> > manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM'
> > ModemManager[1574]: <debug> [1490989871.087122] [mm-plugin-
> > manager.c:902] load_plugins(): Successfully loaded 27 plugins
> > ModemManager[1574]: <debug> [1490989871.094817] [main.c:87]
> > name_acquired_cb(): Service name 'org.freedesktop.ModemManager1'
> > was
> > acquired
> > ModemManager[1574]: <debug> [1490989871.095004] [mm-base-
> > manager.c:479] mm_base_manager_start(): Starting automatic device
> > scan...
> > ModemManager[1574]: <debug> [1490989871.136373] [mm-base-
> > manager.c:518] mm_base_manager_start(): Finished device scan...
> > ModemManager[1574]: <debug> [1490989871.139387] [mm-base-
> > manager.c:314] device_added(): (tty/ttymxc0): port's parent
> > platform
> > driver is not whitelisted
> > ModemManager[1574]: <debug> [1490989871.142340] [mm-base-
> > manager.c:314] device_added(): (tty/ttymxc1): port's parent
> > platform
> > driver is not whitelisted
> > ModemManager[1574]: <debug> [1490989871.144381] [mm-plugin-
> > manager.c:739] mm_plugin_manager_find_device_support(): (Plugin
> > Manager) [/sys/devices/soc0/soc/2100000.aips-bus/21ec000.serial]
> > Checking device support...
> > ModemManager[1574]: <debug> [1490989871.145459] [mm-plugin.c:253]
> > apply_pre_probing_filters(): (Option) [ttymxc2] filtered by drivers
> > ModemManager[1574]: <debug> [1490989871.145571] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Novatel LTE) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.145635] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Linktop) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.145692] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Samsung) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.145746] [mm-plugin.c:253]
> > apply_pre_probing_filters(): (Sierra) [ttymxc2] filtered by drivers
> > ModemManager[1574]: <debug> [1490989871.145797] [mm-plugin.c:253]
> > apply_pre_probing_filters(): (Gobi) [ttymxc2] filtered by drivers
> > ModemManager[1574]: <debug> [1490989871.145848] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (X22X) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.478216] [mm-plugin.c:380]
> > apply_pre_probing_filters(): (MTK) [ttymxc2] filtered by udev tags
> > ModemManager[1574]: <debug> [1490989871.478455] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (AnyDATA) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.479092] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Pantech) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.479279] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Novatel) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.479434] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Motorola) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.479571] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Huawei) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.479697] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (SimTech) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.479819] [mm-plugin.c:253]
> > apply_pre_probing_filters(): (Option High-Speed) [ttymxc2] filtered
> > by
> > drivers
> > ModemManager[1574]: <debug> [1490989871.479946] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Altair LTE) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.480077] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Telit) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.480205] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (ZTE) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.480322] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Wavecom) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.480443] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Longcheer) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.480559] [mm-plugin.c:326]
> > apply_pre_probing_filters(): (Nokia (Icera)) [ttymxc2] filtered by
> > vendor/product IDs
> > ModemManager[1574]: <debug> [1490989871.480698] [mm-plugin.c:380]
> > apply_pre_probing_filters(): (Ericsson MBM) [ttymxc2] filtered by
> > udev
> > tags
> > ModemManager[1574]: <debug> [1490989871.480902] [mm-plugin-
> > manager.c:640] build_plugins_list(): (Plugin Manager) [ttymxc2]
> > Found
> > '5' plugins to try...
> > ModemManager[1574]: <debug> [1490989871.481047] [mm-plugin-
> > manager.c:644] build_plugins_list(): (Plugin Manager)
> > [ttymxc2] Will
> > try with plugin 'Nokia'
> > ModemManager[1574]: <debug> [1490989871.481173] [mm-plugin-
> > manager.c:644] build_plugins_list(): (Plugin Manager)
> > [ttymxc2] Will
> > try with plugin 'Iridium'
> > ModemManager[1574]: <debug> [1490989871.481872] [mm-plugin-
> > manager.c:644] build_plugins_list(): (Plugin Manager)
> > [ttymxc2] Will
> > try with plugin 'Cinterion'
> > ModemManager[1574]: <debug> [1490989871.482025] [mm-plugin-
> > manager.c:644] build_plugins_list(): (Plugin Manager)
> > [ttymxc2] Will
> > try with plugin 'Via CBP7'
> > ModemManager[1574]: <debug> [1490989871.482152] [mm-plugin-
> > manager.c:644] build_plugins_list(): (Plugin Manager)
> > [ttymxc2] Will
> > try with plugin 'Generic'
> > ModemManager[1574]: <debug> [1490989871.482591] [mm-plugin.c:767]
> > mm_plugin_supports_port(): (Nokia) [ttymxc2] probe required: 'at,
> > at-
> > vendor, at-icera'
> > ModemManager[1574]: <debug> [1490989871.482841] [mm-port-
> > probe.c:1353] mm_port_probe_run(): (tty/ttymxc2) launching port
> > probing: 'at, at-vendor, at-icera'
> > ModemManager[1574]: <debug> [1490989871.486040] [mm-base-
> > manager.c:314] device_added(): (tty/ttymxc4): port's parent
> > platform
> > driver is not whitelisted
> > ModemManager[1574]: <debug> [1490989871.504025] [mm-base-
> > manager.c:314] device_added(): (net/can0): port's parent platform
> > driver is not whitelisted
> > ModemManager[1574]: <debug> [1490989871.507236] [mm-base-
> > manager.c:314] device_added(): (net/can1): port's parent platform
> > driver is not whitelisted
> > ModemManager[1574]: <debug> [1490989871.510886] [mm-base-
> > manager.c:314] device_added(): (net/eth0): port's parent platform
> > driver is not whitelisted
> > ModemManager[1574]: <debug> [1490989871.514518] [mm-base-
> > manager.c:290] device_added(): (net/sit0): could not get port's
> > parent
> > device
> > ModemManager[1574]: <debug> [1490989871.521405] [mm-port-
> > serial.c:1099] mm_port_serial_open(): (ttymxc2) opening serial
> > port...
> > ModemManager[1574]: <debug> [1490989871.856299] [mm-port-
> > serial.c:1237] mm_port_serial_open(): (ttymxc2) device open count
> > is
> > 1 (open)
> > ModemManager[1574]: <debug> [1490989871.957590] [mm-port-serial-
> > at.c:440] debug_log(): (ttymxc2): --> 'ATE1 E0<CR>'
> > ModemManager[1574]: <debug> [1490989873.636689] [mm-plugin-
> > manager.c:710] min_probing_timeout_cb(): (Plugin Manager)
> > [/sys/devices/soc0/soc/2100000.aips-bus/21ec000.serial] Minimum
> > probing time consumed
> > ModemManager[1574]: <debug> [1490989875.637443] [mm-port-probe-
> > at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got:
> > 'Serial command timed out'
> > ModemManager[1574]: <debug> [1490989875.638133] [mm-port-serial-
> > at.c:440] debug_log(): (ttymxc2): --> 'ATE1 E0<CR>'
> > ModemManager[1574]: <debug> [1490989879.638923] [mm-port-probe-
> > at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got:
> > 'Serial command timed out'
> > ModemManager[1574]: <debug> [1490989879.639497] [mm-port-serial-
> > at.c:440] debug_log(): (ttymxc2): --> 'ATE1 E0<CR>'
> > ModemManager[1574]: <debug> [1490989883.639253] [mm-port-probe-
> > at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got:
> > 'Serial command timed out'
> > ModemManager[1574]: <debug> [1490989883.639516] [mm-port-
> > probe.c:162]
> > mm_port_probe_set_result_at(): (tty/ttymxc2) port is not AT-capable
> > ModemManager[1574]: <debug> [1490989883.640461] [mm-port-
> > serial.c:1296] mm_port_serial_close(): (ttymxc2) device open count
> > is
> > 0 (close)
> > ModemManager[1574]: <debug> [1490989883.640727] [mm-port-
> > serial.c:1312] mm_port_serial_close(): (ttymxc2) closing serial
> > port...
> > ModemManager[1574]: <debug> [1490989883.645830] [mm-port-
> > serial.c:1361] mm_port_serial_close(): (ttymxc2) serial port closed
> > ModemManager[1574]: <debug> [1490989883.646114] [mm-port-
> > serial.c:1415] port_serial_close_force(): (ttymxc2) forced to
> > close
> > port
> > ModemManager[1574]: <debug> [1490989883.646476] [mm-plugin.c:429]
> > apply_post_probing_filters(): (Nokia) [ttymxc2] filtered by vendor
> > strings
> > ModemManager[1574]: <debug> [1490989883.646948] [mm-plugin.c:767]
> > mm_plugin_supports_port(): (Iridium) [ttymxc2] probe required:
> > 'at,
> > at-vendor, at-product'
> > ModemManager[1574]: <debug> [1490989883.647379] [mm-plugin.c:454]
> > apply_post_probing_filters(): (Iridium) [ttymxc2] filtered as no
> > vendor/product strings given
> > ModemManager[1574]: <debug> [1490989883.647833] [mm-plugin.c:767]
> > mm_plugin_supports_port(): (Cinterion) [ttymxc2] probe required:
> > 'at,
> > at-vendor'
> > ModemManager[1574]: <debug> [1490989883.648736] [mm-plugin.c:429]
> > apply_post_probing_filters(): (Cinterion) [ttymxc2] filtered by
> > vendor
> > strings
> > ModemManager[1574]: <debug> [1490989883.649090] [mm-plugin.c:767]
> > mm_plugin_supports_port(): (Via CBP7) [ttymxc2] probe required:
> > 'at,
> > at-vendor, at-product'
> > ModemManager[1574]: <debug> [1490989883.649355] [mm-plugin.c:454]
> > apply_post_probing_filters(): (Via CBP7) [ttymxc2] filtered as no
> > vendor/product strings given
> > ModemManager[1574]: <debug> [1490989883.649649] [mm-plugin.c:767]
> > mm_plugin_supports_port(): (Generic) [ttymxc2] probe required:
> > 'at,
> > qcdm'
> > ModemManager[1574]: <debug> [1490989883.649820] [mm-port-
> > probe.c:1353] mm_port_probe_run(): (tty/ttymxc2) launching port
> > probing: 'qcdm'
> > ModemManager[1574]: <debug> [1490989883.650022] [mm-port-
> > probe.c:658]
> > serial_probe_qcdm(): (tty/ttymxc2) probing QCDM...
> > ModemManager[1574]: <debug> [1490989883.650447] [mm-port-
> > serial.c:1099] mm_port_serial_open(): (ttymxc2) opening serial
> > port...
> > ModemManager[1574]: <debug> [1490989883.653133] [mm-port-
> > serial.c:1237] mm_port_serial_open(): (ttymxc2) device open count
> > is
> > 1 (open)
> > ModemManager[1574]: <debug> [1490989883.653706] [mm-port-serial-
> > qcdm.c:201] debug_log(): (ttymxc2): --> 7e 00 78 f0 7e
> > ModemManager[1574]: <debug> [1490989886.639025] [mm-port-serial-
> > qcdm.c:201] debug_log(): (ttymxc2): --> 7e 00 78 f0 7e
> > ModemManager[1574]: <debug> [1490989889.638630] [mm-port-
> > probe.c:258]
> > mm_port_probe_set_result_qcdm(): (tty/ttymxc2) port is not QCDM-
> > capable
> > ModemManager[1574]: <debug> [1490989889.639095] [mm-port-
> > serial.c:1296] mm_port_serial_close(): (ttymxc2) device open count
> > is
> > 0 (close)
> > ModemManager[1574]: <debug> [1490989889.639302] [mm-port-
> > serial.c:1312] mm_port_serial_close(): (ttymxc2) closing serial
> > port...
> > ModemManager[1574]: <debug> [1490989889.645367] [mm-port-
> > serial.c:1361] mm_port_serial_close(): (ttymxc2) serial port closed
> > ModemManager[1574]: <debug> [1490989889.645594] [mm-port-
> > serial.c:1415] port_serial_close_force(): (ttymxc2) forced to
> > close
> > port
> > ModemManager[1574]: <debug> [1490989889.645964] [mm-plugin-
> > manager.c:462] plugin_supports_port_ready(): (Plugin Manager)
> > (Generic) [ttymxc2] found best plugin for port
> > ModemManager[1574]: <debug> [1490989889.646272] [mm-plugin-
> > manager.c:316] port_probe_context_finished(): (Plugin Manager)
> > 'ttymxc2' port probe finished, last one in device
> > ModemManager[1574]: <debug> [1490989889.646434] [mm-plugin-
> > manager.c:138] find_device_support_context_complete_and_free():
> > (Plugin Manager) [/sys/devices/soc0/soc/2100000.aips-
> > bus/21ec000.serial] device support check finished in '18.501746'
> > seconds
> > ModemManager[1574]: <info> [1490989889.646644] [mm-device.c:509]
> > mm_device_create_modem(): Creating modem with plugin 'Generic' and
> > '1'
> > ports
> > ModemManager[1574]: <warn> [1490989889.659576] [mm-plugin.c:905]
> > mm_plugin_create_modem(): Could not grab port (tty/ttymxc2):
> > 'Cannot
> > add port 'tty/ttymxc2', unhandled serial type'
> > ModemManager[1574]: <debug> [1490989889.660497] [mm-base-
> > modem.c:1485] finalize(): Modem (Generic)
> > '/sys/devices/soc0/soc/2100000.aips-bus/21ec000.serial' completely
> > disposed
> > ModemManager[1574]: <warn> [1490989889.660761] [mm-base-
> > manager.c:158] find_device_support_ready(): Couldn't create modem
> > for
> > device at '/sys/devices/soc0/soc/2100000.aips-bus/21ec000.serial':
> > Failed to find primary AT port
> >
> > Anyone has an idea why this is happening? I appreciate very much a
> > help from you.
> >
> > Best Regards,
> >
> > Jan Graczyk
> >
> > _______________________________________________
> > ModemManager-devel mailing list
> > ModemManager-devel at lists.freedesktop.org
> > https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
>
> _______________________________________________
> ModemManager-devel mailing list
> ModemManager-devel at lists.freedesktop.org
> https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
More information about the ModemManager-devel
mailing list