Failed to find primary AT port

Fri Mar 31 20:35:28 UTC 2017

Hello All,

I have a problem with ModemManager failing to find primary AT port. Her is the ModemManager debug log:

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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20170331/18cd45a6/attachment-0001.html>