Failed to find primary AT port

Fri Mar 31 20:50:33 UTC 2017

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