Understanding log sequence of events to debug hangup?
Scott Lemon
slemon at twilio.com
Mon May 4 16:31:52 UTC 2020
Hello,
First I'd like to say what a great project! I've got numerous customers
using this now, and am impressed by the capabilities and breadth of support
for modems.
I've run into an issue with a customer who is using ModemManager with a
u-blox modem, and we're seeing a hangup that is being reported after the
roaming registration is complete on AT&T. When we look at our mobile core,
the modem appears to be connected and given an IP address, and the
connection is still up when the hangup is being reported.
I'm doing my best to interpret the logs, and they are very readable. I do
see the sequence of events leading up to the "<debug> [1588357729.967755]
(ttyACM1) unexpected port hangup!" (in this particular log) but am not
clear on how/why this is happening. I'm assuming that maybe this is
detected with a RTS/CTS indication?
The customer is using a u-blox TOBY-R200 modem, and I do not see any u-blox
modems listed on the Supported Devices / compatibility page:
https://www.freedesktop.org/wiki/Software/ModemManager/SupportedDevices/
I'm looking for any tips on how to interpret the log that I have attached,
and if there is anything else obvious that might be causing this issue.
I look forward to learning more about Modem Manager and how it works.
--
Scott C. Lemon
IoT Solutions Engineer
<https://www.Twilio.com>
Mobile: +1 (801) 336-4476
Email: slemon at twilio.com
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20200504/662cbaad/attachment-0001.htm>
-------------- next part --------------
<info> [1588357461.581525] ModemManager (version 1.8.0) starting in system bus...
<debug> [1588357461.685761] Bus acquired, creating manager...
<debug> [1588357461.704532] [filter] created
<debug> [1588357461.712146] [filter] explicit whitelist: yes
<debug> [1588357461.718263] [filter] virtual devices forbidden: yes
<debug> [1588357461.724196] [filter] net devices allowed: yes
<debug> [1588357461.730701] [filter] cdc-wdm devices allowed: yes
<debug> [1588357461.737215] [filter] tty devices:
<debug> [1588357461.743460] [filter] blacklist applied: yes
<debug> [1588357461.751999] [filter] manual scan only applied: yes
<debug> [1588357461.757067] [filter] platform driver check: yes
<debug> [1588357461.763147] [filter] driver check: no
<debug> [1588357461.769426] [filter] cdc-acm interface check: no
<debug> [1588357461.778725] [filter] with net check: no
<debug> [1588357461.786773] [filter] default: allowed
<debug> [1588357461.793372] [plugin manager] looking for plugins in '/usr/lib/ModemManager'
<debug> [1588357461.811910] [plugin manager] loaded plugin 'Sierra (legacy)'
<debug> [1588357461.821594] [plugin manager] loaded plugin 'Via CBP7'
<debug> [1588357461.829332] [plugin manager] loaded plugin 'Pantech'
<debug> [1588357461.843095] [plugin manager] loaded plugin 'Wavecom'
<debug> [1588357461.854051] [plugin manager] loaded plugin 'u-blox'
<debug> [1588357461.861876] [plugin manager] loaded plugin 'SimTech'
<debug> [1588357461.872468] [plugin manager] loaded plugin 'X22X'
<debug> [1588357461.899596] [plugin manager] loaded plugin 'Ericsson MBM'
<debug> [1588357461.908436] [plugin manager] loaded plugin 'Nokia'
<debug> [1588357461.916796] [plugin manager] loaded plugin 'Thuraya'
<debug> [1588357461.929222] [plugin manager] loaded plugin 'Novatel'
<debug> [1588357461.946259] [plugin manager] loaded plugin 'Option High-Speed'
<debug> [1588357461.969173] [plugin manager] loaded plugin 'Novatel LTE'
<debug> [1588357461.989241] [plugin manager] loaded plugin 'Dell'
<debug> [1588357462.014378] [plugin manager] loaded plugin 'Linktop'
<debug> [1588357462.034904] [plugin manager] loaded plugin 'Nokia (Icera)'
<debug> [1588357462.050726] [plugin manager] loaded plugin 'Cinterion'
<debug> [1588357462.085633] [plugin manager] loaded plugin 'Samsung'
<debug> [1588357462.096389] [plugin manager] loaded plugin 'Generic'
<debug> [1588357462.118360] [plugin manager] loaded plugin 'Sierra'
<debug> [1588357462.126785] [plugin manager] loaded plugin 'Longcheer'
<debug> [1588357462.136705] [plugin manager] loaded plugin 'Telit'
<debug> [1588357462.156029] [plugin manager] loaded plugin 'Huawei'
<debug> [1588357462.172999] [plugin manager] loaded plugin 'AnyDATA'
<debug> [1588357462.183364] [plugin manager] loaded plugin 'Motorola'
<debug> [1588357462.206294] [plugin manager] loaded plugin 'Option'
<debug> [1588357462.225678] [plugin manager] loaded plugin 'ZTE'
<debug> [1588357462.243476] [plugin manager] loaded plugin 'Altair LTE'
<debug> [1588357462.260881] [plugin manager] loaded plugin 'Iridium'
<debug> [1588357462.276253] [plugin manager] loaded plugin 'MTK'
<debug> [1588357462.285937] [plugin manager] loaded plugin 'Haier'
<debug> [1588357462.302256] [plugin manager] loaded plugin 'Quectel'
<debug> [1588357462.314542] [plugin manager] successfully loaded 32 plugins
<debug> [1588357462.331287] Service name 'org.freedesktop.ModemManager1' was acquired
<debug> [1588357462.336490] Starting automatic device scan...
<debug> [1588357462.373413] Finished device scan...
<debug> [1588357462.397431] (tty/ttymxc0): adding device at sysfs path: /sys/devices/platform/soc/2000000.aips-bus/2000000.spba-bus/2020000.serial/tty/ttymxc0
<debug> [1588357462.411047] [filter] (tty/ttymxc0): port filtered: port's parent platform driver is not whitelisted
<debug> [1588357462.416486] (tty/ttymxc3): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/21f0000.serial/tty/ttymxc3
<debug> [1588357462.424322] [filter] (tty/ttymxc3): port filtered: port's parent platform driver is not whitelisted
<debug> [1588357462.434987] (tty/console): adding device at sysfs path: /sys/devices/virtual/tty/console
<debug> [1588357462.440838] [filter] (tty/console) port filtered: virtual device
<debug> [1588357462.446917] (tty/ptmx): adding device at sysfs path: /sys/devices/virtual/tty/ptmx
<debug> [1588357462.454546] [filter] (tty/ptmx) port filtered: virtual device
<debug> [1588357462.464597] (tty/tty): adding device at sysfs path: /sys/devices/virtual/tty/tty
<debug> [1588357462.470422] [filter] (tty/tty) port filtered: virtual device
<debug> [1588357462.478046] (tty/tty0): adding device at sysfs path: /sys/devices/virtual/tty/tty0
<debug> [1588357462.485602] [filter] (tty/tty0) port filtered: virtual device
<debug> [1588357462.495180] (tty/tty1): adding device at sysfs path: /sys/devices/virtual/tty/tty1
<debug> [1588357462.509539] [filter] (tty/tty1) port filtered: virtual device
<debug> [1588357462.521530] (tty/tty10): adding device at sysfs path: /sys/devices/virtual/tty/tty10
<debug> [1588357462.529167] [filter] (tty/tty10) port filtered: virtual device
<debug> [1588357462.544874] (tty/tty11): adding device at sysfs path: /sys/devices/virtual/tty/tty11
<debug> [1588357462.559559] [filter] (tty/tty11) port filtered: virtual device
<debug> [1588357462.567081] (tty/tty12): adding device at sysfs path: /sys/devices/virtual/tty/tty12
<debug> [1588357462.583566] [filter] (tty/tty12) port filtered: virtual device
<debug> [1588357462.590994] (tty/tty13): adding device at sysfs path: /sys/devices/virtual/tty/tty13
<debug> [1588357462.600015] [filter] (tty/tty13) port filtered: virtual device
<debug> [1588357462.608559] (tty/tty14): adding device at sysfs path: /sys/devices/virtual/tty/tty14
<debug> [1588357462.614305] [filter] (tty/tty14) port filtered: virtual device
<debug> [1588357462.619615] (tty/tty15): adding device at sysfs path: /sys/devices/virtual/tty/tty15
<debug> [1588357462.628671] [filter] (tty/tty15) port filtered: virtual device
<debug> [1588357462.636150] (tty/tty16): adding device at sysfs path: /sys/devices/virtual/tty/tty16
<debug> [1588357462.650767] [filter] (tty/tty16) port filtered: virtual device
<debug> [1588357462.664033] (tty/tty17): adding device at sysfs path: /sys/devices/virtual/tty/tty17
<debug> [1588357462.696198] [filter] (tty/tty17) port filtered: virtual device
<debug> [1588357462.717076] (tty/tty18): adding device at sysfs path: /sys/devices/virtual/tty/tty18
<debug> [1588357462.728589] [filter] (tty/tty18) port filtered: virtual device
<debug> [1588357462.735352] (tty/tty19): adding device at sysfs path: /sys/devices/virtual/tty/tty19
<debug> [1588357462.745890] [filter] (tty/tty19) port filtered: virtual device
<debug> [1588357462.754651] (tty/tty2): adding device at sysfs path: /sys/devices/virtual/tty/tty2
<debug> [1588357462.765017] [filter] (tty/tty2) port filtered: virtual device
<debug> [1588357462.778480] (tty/tty20): adding device at sysfs path: /sys/devices/virtual/tty/tty20
<debug> [1588357462.788766] [filter] (tty/tty20) port filtered: virtual device
<debug> [1588357462.795096] (tty/tty21): adding device at sysfs path: /sys/devices/virtual/tty/tty21
<debug> [1588357462.803185] [filter] (tty/tty21) port filtered: virtual device
<debug> [1588357462.816030] (tty/tty22): adding device at sysfs path: /sys/devices/virtual/tty/tty22
<debug> [1588357462.822828] [filter] (tty/tty22) port filtered: virtual device
<debug> [1588357462.829052] (tty/tty23): adding device at sysfs path: /sys/devices/virtual/tty/tty23
<debug> [1588357462.834847] [filter] (tty/tty23) port filtered: virtual device
<debug> [1588357462.840146] (tty/tty24): adding device at sysfs path: /sys/devices/virtual/tty/tty24
<debug> [1588357462.847664] [filter] (tty/tty24) port filtered: virtual device
<debug> [1588357462.860772] (tty/tty25): adding device at sysfs path: /sys/devices/virtual/tty/tty25
<debug> [1588357462.870806] [filter] (tty/tty25) port filtered: virtual device
<debug> [1588357462.884601] (tty/tty26): adding device at sysfs path: /sys/devices/virtual/tty/tty26
<debug> [1588357462.910056] [filter] (tty/tty26) port filtered: virtual device
<debug> [1588357462.925265] (tty/tty27): adding device at sysfs path: /sys/devices/virtual/tty/tty27
<debug> [1588357462.944981] [filter] (tty/tty27) port filtered: virtual device
<debug> [1588357462.962979] (tty/tty28): adding device at sysfs path: /sys/devices/virtual/tty/tty28
<debug> [1588357462.971801] [filter] (tty/tty28) port filtered: virtual device
<debug> [1588357462.985028] (tty/tty29): adding device at sysfs path: /sys/devices/virtual/tty/tty29
<debug> [1588357463.005342] [filter] (tty/tty29) port filtered: virtual device
<debug> [1588357463.017723] (tty/tty3): adding device at sysfs path: /sys/devices/virtual/tty/tty3
<debug> [1588357463.030765] [filter] (tty/tty3) port filtered: virtual device
<debug> [1588357463.045201] (tty/tty30): adding device at sysfs path: /sys/devices/virtual/tty/tty30
<debug> [1588357463.066921] [filter] (tty/tty30) port filtered: virtual device
<debug> [1588357463.080745] (tty/tty31): adding device at sysfs path: /sys/devices/virtual/tty/tty31
<debug> [1588357463.103514] [filter] (tty/tty31) port filtered: virtual device
<debug> [1588357463.115958] (tty/tty32): adding device at sysfs path: /sys/devices/virtual/tty/tty32
<debug> [1588357463.129150] [filter] (tty/tty32) port filtered: virtual device
<debug> [1588357463.162666] (tty/tty33): adding device at sysfs path: /sys/devices/virtual/tty/tty33
<debug> [1588357463.178335] [filter] (tty/tty33) port filtered: virtual device
<debug> [1588357463.207174] (tty/tty34): adding device at sysfs path: /sys/devices/virtual/tty/tty34
<debug> [1588357463.233852] [filter] (tty/tty34) port filtered: virtual device
<debug> [1588357463.286040] (tty/tty35): adding device at sysfs path: /sys/devices/virtual/tty/tty35
<debug> [1588357463.310184] [filter] (tty/tty35) port filtered: virtual device
<debug> [1588357463.321165] (tty/tty36): adding device at sysfs path: /sys/devices/virtual/tty/tty36
<debug> [1588357463.334359] [filter] (tty/tty36) port filtered: virtual device
<debug> [1588357463.351920] (tty/tty37): adding device at sysfs path: /sys/devices/virtual/tty/tty37
<debug> [1588357463.373029] [filter] (tty/tty37) port filtered: virtual device
<debug> [1588357463.388611] (tty/tty38): adding device at sysfs path: /sys/devices/virtual/tty/tty38
<debug> [1588357463.406083] [filter] (tty/tty38) port filtered: virtual device
<debug> [1588357463.416671] (tty/tty39): adding device at sysfs path: /sys/devices/virtual/tty/tty39
<debug> [1588357463.434971] [filter] (tty/tty39) port filtered: virtual device
<debug> [1588357463.454603] (tty/tty4): adding device at sysfs path: /sys/devices/virtual/tty/tty4
<debug> [1588357463.473891] [filter] (tty/tty4) port filtered: virtual device
<debug> [1588357463.486158] (tty/tty40): adding device at sysfs path: /sys/devices/virtual/tty/tty40
<debug> [1588357463.499478] [filter] (tty/tty40) port filtered: virtual device
<debug> [1588357463.517718] (tty/tty41): adding device at sysfs path: /sys/devices/virtual/tty/tty41
<debug> [1588357463.535042] [filter] (tty/tty41) port filtered: virtual device
<debug> [1588357463.556212] (tty/tty42): adding device at sysfs path: /sys/devices/virtual/tty/tty42
<debug> [1588357463.572541] [filter] (tty/tty42) port filtered: virtual device
<debug> [1588357463.584579] (tty/tty43): adding device at sysfs path: /sys/devices/virtual/tty/tty43
<debug> [1588357463.597844] [filter] (tty/tty43) port filtered: virtual device
<debug> [1588357463.612567] (tty/tty44): adding device at sysfs path: /sys/devices/virtual/tty/tty44
<debug> [1588357463.625637] [filter] (tty/tty44) port filtered: virtual device
<debug> [1588357463.636215] (tty/tty45): adding device at sysfs path: /sys/devices/virtual/tty/tty45
<debug> [1588357463.646165] [filter] (tty/tty45) port filtered: virtual device
<debug> [1588357463.659314] (tty/tty46): adding device at sysfs path: /sys/devices/virtual/tty/tty46
<debug> [1588357463.676361] [filter] (tty/tty46) port filtered: virtual device
<debug> [1588357463.694550] (tty/tty47): adding device at sysfs path: /sys/devices/virtual/tty/tty47
<debug> [1588357463.705036] [filter] (tty/tty47) port filtered: virtual device
<debug> [1588357463.717278] (tty/tty48): adding device at sysfs path: /sys/devices/virtual/tty/tty48
<debug> [1588357463.725588] [filter] (tty/tty48) port filtered: virtual device
<debug> [1588357463.734612] (tty/tty49): adding device at sysfs path: /sys/devices/virtual/tty/tty49
<debug> [1588357463.745065] [filter] (tty/tty49) port filtered: virtual device
<debug> [1588357463.764505] (tty/tty5): adding device at sysfs path: /sys/devices/virtual/tty/tty5
<debug> [1588357463.772592] [filter] (tty/tty5) port filtered: virtual device
<debug> [1588357463.784518] (tty/tty50): adding device at sysfs path: /sys/devices/virtual/tty/tty50
<debug> [1588357463.794986] [filter] (tty/tty50) port filtered: virtual device
<debug> [1588357463.807482] (tty/tty51): adding device at sysfs path: /sys/devices/virtual/tty/tty51
<debug> [1588357463.814898] [filter] (tty/tty51) port filtered: virtual device
<debug> [1588357463.823697] (tty/tty52): adding device at sysfs path: /sys/devices/virtual/tty/tty52
<debug> [1588357463.831071] [filter] (tty/tty52) port filtered: virtual device
<debug> [1588357463.838428] (tty/tty53): adding device at sysfs path: /sys/devices/virtual/tty/tty53
<debug> [1588357463.848887] [filter] (tty/tty53) port filtered: virtual device
<debug> [1588357463.855315] (tty/tty54): adding device at sysfs path: /sys/devices/virtual/tty/tty54
<debug> [1588357463.866331] [filter] (tty/tty54) port filtered: virtual device
<debug> [1588357463.889000] (tty/tty55): adding device at sysfs path: /sys/devices/virtual/tty/tty55
<debug> [1588357463.909795] [filter] (tty/tty55) port filtered: virtual device
<debug> [1588357463.915900] (tty/tty56): adding device at sysfs path: /sys/devices/virtual/tty/tty56
<debug> [1588357463.930928] [filter] (tty/tty56) port filtered: virtual device
<debug> [1588357463.943665] (tty/tty57): adding device at sysfs path: /sys/devices/virtual/tty/tty57
<debug> [1588357463.957009] [filter] (tty/tty57) port filtered: virtual device
<debug> [1588357463.968766] (tty/tty58): adding device at sysfs path: /sys/devices/virtual/tty/tty58
<debug> [1588357463.980217] [filter] (tty/tty58) port filtered: virtual device
<debug> [1588357463.988232] (tty/tty59): adding device at sysfs path: /sys/devices/virtual/tty/tty59
<debug> [1588357463.997570] [filter] (tty/tty59) port filtered: virtual device
<debug> [1588357464.009567] (tty/tty6): adding device at sysfs path: /sys/devices/virtual/tty/tty6
<debug> [1588357464.047457] [filter] (tty/tty6) port filtered: virtual device
<debug> [1588357464.055753] (tty/tty60): adding device at sysfs path: /sys/devices/virtual/tty/tty60
<debug> [1588357464.077094] [filter] (tty/tty60) port filtered: virtual device
<debug> [1588357464.084498] (tty/tty61): adding device at sysfs path: /sys/devices/virtual/tty/tty61
<debug> [1588357464.092809] [filter] (tty/tty61) port filtered: virtual device
<debug> [1588357464.100555] (tty/tty62): adding device at sysfs path: /sys/devices/virtual/tty/tty62
<debug> [1588357464.116469] [filter] (tty/tty62) port filtered: virtual device
<debug> [1588357464.132912] (tty/tty63): adding device at sysfs path: /sys/devices/virtual/tty/tty63
<debug> [1588357464.146251] [filter] (tty/tty63) port filtered: virtual device
<debug> [1588357464.156038] (tty/tty7): adding device at sysfs path: /sys/devices/virtual/tty/tty7
<debug> [1588357464.175031] [filter] (tty/tty7) port filtered: virtual device
<debug> [1588357464.185828] (tty/tty8): adding device at sysfs path: /sys/devices/virtual/tty/tty8
<debug> [1588357464.194200] [filter] (tty/tty8) port filtered: virtual device
<debug> [1588357464.219872] (tty/tty9): adding device at sysfs path: /sys/devices/virtual/tty/tty9
<debug> [1588357464.230482] [filter] (tty/tty9) port filtered: virtual device
<debug> [1588357464.245479] (net/can0): adding device at sysfs path: /sys/devices/platform/soc/2000000.aips-bus/2090000.can/net/can0
<debug> [1588357464.262467] [filter] (net/can0) port allowed: net device
<debug> [1588357464.284985] (net/can0): first port in device /sys/devices/platform/soc/2000000.aips-bus/2090000.can
<debug> [1588357464.312788] [plugin manager] task 0: new support task for device: /sys/devices/platform/soc/2000000.aips-bus/2090000.can
<debug> [1588357464.323601] [plugin manager] task 0: port grabbed: can0
<debug> [1588357464.334683] [plugin manager] task 0,can0: new support task for port
<debug> [1588357464.353162] [plugin manager) task 0,can0: deferred until min wait time elapsed
<debug> [1588357464.383320] (net/can1): adding device at sysfs path: /sys/devices/platform/soc/2000000.aips-bus/2094000.can/net/can1
<debug> [1588357464.392082] [filter] (net/can1) port allowed: net device
<debug> [1588357464.398855] (net/can1): first port in device /sys/devices/platform/soc/2000000.aips-bus/2094000.can
<debug> [1588357464.420698] [plugin manager] task 1: new support task for device: /sys/devices/platform/soc/2000000.aips-bus/2094000.can
<debug> [1588357464.429521] [plugin manager] task 1: port grabbed: can1
<debug> [1588357464.439360] [plugin manager] task 1,can1: new support task for port
<debug> [1588357464.447105] [plugin manager) task 1,can1: deferred until min wait time elapsed
<debug> [1588357464.458214] (net/eth0): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2188000.ethernet/net/eth0
<debug> [1588357464.468060] [filter] (net/eth0) port allowed: net device
<debug> [1588357464.474883] (net/eth0): first port in device /sys/devices/platform/soc/2100000.aips-bus/2188000.ethernet
<debug> [1588357464.484462] [plugin manager] task 2: new support task for device: /sys/devices/platform/soc/2100000.aips-bus/2188000.ethernet
<debug> [1588357464.502386] [plugin manager] task 2: port grabbed: eth0
<debug> [1588357464.513650] [plugin manager] task 2,eth0: new support task for port
<debug> [1588357464.523092] [plugin manager) task 2,eth0: deferred until min wait time elapsed
<debug> [1588357464.533425] (net/mlan0): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2190000.usdhc/mmc_host/mmc0/mmc0:0001/mmc0:0001:1/net/mlan0
<debug> [1588357464.566570] [filter] (net/mlan0) port allowed: net device
<debug> [1588357464.610929] (net/mlan0): first port in device /sys/devices/platform/soc/2100000.aips-bus/2190000.usdhc/mmc_host/mmc0/mmc0:0001/mmc0:0001:1
<debug> [1588357464.626133] [plugin manager] task 3: new support task for device: /sys/devices/platform/soc/2100000.aips-bus/2190000.usdhc/mmc_host/mmc0/mmc0:0001/mmc0:0001:1
<debug> [1588357464.650415] (net/mlan0) could not get vendor/product id
<debug> [1588357464.671489] (net/mlan0) could not get vendor/product id
<debug> [1588357464.692119] [plugin manager] task 3: port grabbed: mlan0
<debug> [1588357464.705213] [plugin manager] task 3,mlan0: new support task for port
<debug> [1588357464.723674] [plugin manager) task 3,mlan0: deferred until min wait time elapsed
<debug> [1588357464.743547] (net/uap0): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2190000.usdhc/mmc_host/mmc0/mmc0:0001/mmc0:0001:1/net/uap0
<debug> [1588357464.775572] [filter] (net/uap0) port allowed: net device
<debug> [1588357464.795818] (net/uap0): additional port in device /sys/devices/platform/soc/2100000.aips-bus/2190000.usdhc/mmc_host/mmc0/mmc0:0001/mmc0:0001:1
<debug> [1588357464.812828] (net/uap0) could not get vendor/product id
<debug> [1588357464.825621] (net/uap0) could not get vendor/product id
<debug> [1588357464.844066] [plugin manager] task 3: port grabbed: uap0
<debug> [1588357464.870259] [plugin manager] task 3,uap0: new support task for port
<debug> [1588357464.900150] [plugin manager) task 3,uap0: deferred until min wait time elapsed
<debug> [1588357464.932117] (net/lo): adding device at sysfs path: /sys/devices/virtual/net/lo
<debug> [1588357464.957937] [filter] (net/lo) port filtered: virtual device
<debug> [1588357464.978674] (net/sit0): adding device at sysfs path: /sys/devices/virtual/net/sit0
<debug> [1588357464.996355] [filter] (net/sit0) port filtered: virtual device
<debug> [1588357465.824153] [plugin manager] task 0: min wait time elapsed
<debug> [1588357465.846216] (Sierra (legacy)) [can0] filtered by drivers
<debug> [1588357465.874286] (Via CBP7) [can0] filtered by subsystem
<debug> [1588357465.904349] (Pantech) [can0] filtered by vendor/product IDs
<debug> [1588357465.937259] (Wavecom) [can0] filtered by subsystem
<debug> [1588357465.967574] (u-blox) [can0] filtered by vendor/product IDs
<debug> [1588357465.988857] (SimTech) [can0] filtered by vendor/product IDs
<debug> [1588357466.018294] (X22X) [can0] filtered by vendor/product IDs
<debug> [1588357466.048978] (Ericsson MBM) [can0] filtered by udev tags
<debug> [1588357466.084314] (Nokia) [can0] filtered by subsystem
<debug> [1588357466.105001] (Thuraya) [can0] filtered by subsystem
<debug> [1588357466.154315] (Novatel) [can0] filtered by vendor/product IDs
<debug> [1588357466.188607] (Option High-Speed) [can0] filtered by drivers
<debug> [1588357466.208851] (Novatel LTE) [can0] filtered by vendor/product IDs
<debug> [1588357466.234457] (Dell) [can0] filtered by vendor/product IDs
<debug> [1588357466.254289] (Linktop) [can0] filtered by subsystem
<debug> [1588357466.299667] (Nokia (Icera)) [can0] filtered by vendor/product IDs
<debug> [1588357466.362241] (Cinterion) [can0] filtered by vendor/product IDs
<debug> [1588357466.393649] (Samsung) [can0] filtered by vendor/product IDs
<debug> [1588357466.444201] (Sierra) [can0] filtered by drivers
<debug> [1588357466.510708] (Longcheer) [can0] filtered by subsystem
<debug> [1588357466.544263] (Telit) [can0] filtered by vendor/product IDs
<debug> [1588357466.556506] (Huawei) [can0] filtered by vendor/product IDs
<debug> [1588357466.586481] (AnyDATA) [can0] filtered by vendor/product IDs
<debug> [1588357466.610427] (Motorola) [can0] filtered by subsystem
<debug> [1588357466.624950] (Option) [can0] filtered by subsystem
<debug> [1588357466.634912] (ZTE) [can0] filtered by vendor/product IDs
<debug> [1588357466.645559] (Altair LTE) [can0] filtered by vendor/product IDs
<debug> [1588357466.678843] (Iridium) [can0] filtered by subsystem
<debug> [1588357466.699084] (MTK) [can0] filtered by subsystem
<debug> [1588357466.725595] (Haier) [can0] filtered by subsystem
<debug> [1588357466.744295] (Quectel) [can0] filtered by drivers
<debug> [1588357466.765153] [plugin manager] task 0,can0: found '1' plugins to try
<debug> [1588357466.783077] [plugin manager] task 0,can0: will try with plugin 'Generic'
<debug> [1588357466.804956] [plugin manager) task 0,can0: started
<debug> [1588357466.838881] [plugin manager] task 0,can0: checking with plugin 'Generic'
<debug> [1588357466.852601] (Generic) [can0] probing deferred until result suggested
<debug> [1588357466.905279] [plugin manager] task 0: min probing time elapsed
<debug> [1588357466.934303] [plugin Manager] task 0: still 1 running probes (1 active): can0
<debug> [1588357466.950474] [plugin manager] task 1: min wait time elapsed
<debug> [1588357466.961388] (Sierra (legacy)) [can1] filtered by drivers
<debug> [1588357466.984245] (Via CBP7) [can1] filtered by subsystem
<debug> [1588357467.013696] (Pantech) [can1] filtered by vendor/product IDs
<debug> [1588357467.044244] (Wavecom) [can1] filtered by subsystem
<debug> [1588357467.074854] (u-blox) [can1] filtered by vendor/product IDs
<debug> [1588357467.102699] (SimTech) [can1] filtered by vendor/product IDs
<debug> [1588357467.113175] (X22X) [can1] filtered by vendor/product IDs
<debug> [1588357467.143401] (Ericsson MBM) [can1] filtered by udev tags
<debug> [1588357467.176949] (Nokia) [can1] filtered by subsystem
<debug> [1588357467.197018] (Thuraya) [can1] filtered by subsystem
<debug> [1588357467.213564] (Novatel) [can1] filtered by vendor/product IDs
<debug> [1588357467.238256] (Option High-Speed) [can1] filtered by drivers
<debug> [1588357467.290644] (Novatel LTE) [can1] filtered by vendor/product IDs
<debug> [1588357467.304739] (Dell) [can1] filtered by vendor/product IDs
<debug> [1588357467.326559] (Linktop) [can1] filtered by subsystem
<debug> [1588357467.333163] (Nokia (Icera)) [can1] filtered by vendor/product IDs
<debug> [1588357467.344244] (Cinterion) [can1] filtered by vendor/product IDs
<debug> [1588357467.359503] (Samsung) [can1] filtered by vendor/product IDs
<debug> [1588357467.396203] (Sierra) [can1] filtered by drivers
<debug> [1588357467.404266] (Longcheer) [can1] filtered by subsystem
<debug> [1588357467.424302] (Telit) [can1] filtered by vendor/product IDs
<debug> [1588357467.430544] (Huawei) [can1] filtered by vendor/product IDs
<debug> [1588357467.444909] (AnyDATA) [can1] filtered by vendor/product IDs
<debug> [1588357467.458228] (Motorola) [can1] filtered by subsystem
<debug> [1588357467.464863] (Option) [can1] filtered by subsystem
<debug> [1588357467.471020] (ZTE) [can1] filtered by vendor/product IDs
<debug> [1588357467.479010] (Altair LTE) [can1] filtered by vendor/product IDs
<debug> [1588357467.494235] (Iridium) [can1] filtered by subsystem
<debug> [1588357467.508072] (MTK) [can1] filtered by subsystem
<debug> [1588357467.537774] (Haier) [can1] filtered by subsystem
<debug> [1588357467.543889] (Quectel) [can1] filtered by drivers
<debug> [1588357467.550101] [plugin manager] task 1,can1: found '1' plugins to try
<debug> [1588357467.563143] [plugin manager] task 1,can1: will try with plugin 'Generic'
<debug> [1588357467.569785] [plugin manager) task 1,can1: started
<debug> [1588357467.575721] [plugin manager] task 1,can1: checking with plugin 'Generic'
<debug> [1588357467.584377] (Generic) [can1] probing deferred until result suggested
<debug> [1588357467.604348] [plugin manager] task 2: min wait time elapsed
<debug> [1588357467.628829] (Sierra (legacy)) [eth0] filtered by drivers
<debug> [1588357467.637866] (Via CBP7) [eth0] filtered by subsystem
<debug> [1588357467.665483] (Pantech) [eth0] filtered by vendor/product IDs
<debug> [1588357467.677654] (Wavecom) [eth0] filtered by subsystem
<debug> [1588357467.690984] (u-blox) [eth0] filtered by vendor/product IDs
<debug> [1588357467.704273] (SimTech) [eth0] filtered by vendor/product IDs
<debug> [1588357467.724301] (X22X) [eth0] filtered by vendor/product IDs
<debug> [1588357467.731414] (Ericsson MBM) [eth0] filtered by udev tags
<debug> [1588357467.741598] (Nokia) [eth0] filtered by subsystem
<debug> [1588357467.767094] (Thuraya) [eth0] filtered by subsystem
<debug> [1588357467.787504] (Novatel) [eth0] filtered by vendor/product IDs
<debug> [1588357467.804288] (Option High-Speed) [eth0] filtered by drivers
<debug> [1588357467.818342] (Novatel LTE) [eth0] filtered by vendor/product IDs
<debug> [1588357467.834317] (Dell) [eth0] filtered by vendor/product IDs
<debug> [1588357467.844287] (Linktop) [eth0] filtered by subsystem
<debug> [1588357467.854213] (Nokia (Icera)) [eth0] filtered by vendor/product IDs
<debug> [1588357467.860340] (Cinterion) [eth0] filtered by vendor/product IDs
<debug> [1588357467.874915] (Samsung) [eth0] filtered by vendor/product IDs
<debug> [1588357467.884994] (Sierra) [eth0] filtered by drivers
<debug> [1588357467.891244] (Longcheer) [eth0] filtered by subsystem
<debug> [1588357467.898491] (Telit) [eth0] filtered by vendor/product IDs
<debug> [1588357467.914300] (Huawei) [eth0] filtered by vendor/product IDs
<debug> [1588357467.924972] (AnyDATA) [eth0] filtered by vendor/product IDs
<debug> [1588357467.934974] (Motorola) [eth0] filtered by subsystem
<debug> [1588357467.941849] (Option) [eth0] filtered by subsystem
<debug> [1588357467.951818] (ZTE) [eth0] filtered by vendor/product IDs
<debug> [1588357467.974308] (Altair LTE) [eth0] filtered by vendor/product IDs
<debug> [1588357467.984952] (Iridium) [eth0] filtered by subsystem
<debug> [1588357468.011227] (MTK) [eth0] filtered by subsystem
<debug> [1588357468.027595] (Haier) [eth0] filtered by subsystem
<debug> [1588357468.032502] (Quectel) [eth0] filtered by drivers
<debug> [1588357468.038672] [plugin manager] task 2,eth0: found '1' plugins to try
<debug> [1588357468.057798] [plugin manager] task 2,eth0: will try with plugin 'Generic'
<debug> [1588357468.064958] [plugin manager) task 2,eth0: started
<debug> [1588357468.084253] [plugin manager] task 2,eth0: checking with plugin 'Generic'
<debug> [1588357468.098675] (Generic) [eth0] probing deferred until result suggested
<debug> [1588357468.115021] [plugin manager] task 3: min wait time elapsed
<debug> [1588357468.122213] (Sierra (legacy)) [uap0] filtered by drivers
<debug> [1588357468.154312] (Via CBP7) [uap0] filtered by subsystem
<debug> [1588357468.171402] (Pantech) [uap0] filtered by vendor/product IDs
<debug> [1588357468.177184] (Wavecom) [uap0] filtered by subsystem
<debug> [1588357468.184553] (u-blox) [uap0] filtered by vendor/product IDs
<debug> [1588357468.194882] (SimTech) [uap0] filtered by vendor/product IDs
<debug> [1588357468.219596] (X22X) [uap0] filtered by vendor/product IDs
<debug> [1588357468.224958] (Ericsson MBM) [uap0] filtered by udev tags
<debug> [1588357468.234240] (Nokia) [uap0] filtered by subsystem
<debug> [1588357468.240617] (Thuraya) [uap0] filtered by subsystem
<debug> [1588357468.254953] (Novatel) [uap0] filtered by vendor/product IDs
<debug> [1588357468.264267] (Option High-Speed) [uap0] filtered by drivers
<debug> [1588357468.284304] (Novatel LTE) [uap0] filtered by vendor/product IDs
<debug> [1588357468.309126] (Dell) [uap0] filtered by vendor/product IDs
<debug> [1588357468.324307] (Linktop) [uap0] filtered by subsystem
<debug> [1588357468.344027] (Nokia (Icera)) [uap0] filtered by vendor/product IDs
<debug> [1588357468.365225] (Cinterion) [uap0] filtered by vendor/product IDs
<debug> [1588357468.384997] (Samsung) [uap0] filtered by vendor/product IDs
<debug> [1588357468.391330] (Sierra) [uap0] filtered by drivers
<debug> [1588357468.408258] (Longcheer) [uap0] filtered by subsystem
<debug> [1588357468.413418] (Telit) [uap0] filtered by vendor/product IDs
<debug> [1588357468.424967] (Huawei) [uap0] filtered by vendor/product IDs
<debug> [1588357468.434944] (AnyDATA) [uap0] filtered by vendor/product IDs
<debug> [1588357468.440043] (Motorola) [uap0] filtered by subsystem
<debug> [1588357468.454972] (Option) [uap0] filtered by subsystem
<debug> [1588357468.464910] (ZTE) [uap0] filtered by vendor/product IDs
<debug> [1588357468.473093] (Altair LTE) [uap0] filtered by vendor/product IDs
<debug> [1588357468.484293] (Iridium) [uap0] filtered by subsystem
<debug> [1588357468.501534] (MTK) [uap0] filtered by subsystem
<debug> [1588357468.512368] (Haier) [uap0] filtered by subsystem
<debug> [1588357468.518294] (Quectel) [uap0] filtered by drivers
<debug> [1588357468.528013] [plugin manager] task 3,uap0: found '1' plugins to try
<debug> [1588357468.544273] [plugin manager] task 3,uap0: will try with plugin 'Generic'
<debug> [1588357468.550399] [plugin manager) task 3,uap0: started
<debug> [1588357468.556285] [plugin manager] task 3,uap0: checking with plugin 'Generic'
<debug> [1588357468.564867] (Generic) [uap0] probing deferred until result suggested
<debug> [1588357468.574985] (Sierra (legacy)) [mlan0] filtered by drivers
<debug> [1588357468.584489] (Via CBP7) [mlan0] filtered by subsystem
<debug> [1588357468.591867] (Pantech) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.597385] (Wavecom) [mlan0] filtered by subsystem
<debug> [1588357468.617400] (u-blox) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.635042] (SimTech) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.640981] (X22X) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.645955] (Ericsson MBM) [mlan0] filtered by udev tags
<debug> [1588357468.654848] (Nokia) [mlan0] filtered by subsystem
<debug> [1588357468.660850] (Thuraya) [mlan0] filtered by subsystem
<debug> [1588357468.666636] (Novatel) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.674879] (Option High-Speed) [mlan0] filtered by drivers
<debug> [1588357468.684952] (Novatel LTE) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.694904] (Dell) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.700818] (Linktop) [mlan0] filtered by subsystem
<debug> [1588357468.714968] (Nokia (Icera)) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.723702] (Cinterion) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.734927] (Samsung) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.741777] (Sierra) [mlan0] filtered by drivers
<debug> [1588357468.746852] (Longcheer) [mlan0] filtered by subsystem
<debug> [1588357468.756777] (Telit) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.762805] (Huawei) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.775799] (AnyDATA) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.785758] (Motorola) [mlan0] filtered by subsystem
<debug> [1588357468.796824] (Option) [mlan0] filtered by subsystem
<debug> [1588357468.802742] (ZTE) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.807685] (Altair LTE) [mlan0] filtered by vendor/product IDs
<debug> [1588357468.815689] (Iridium) [mlan0] filtered by subsystem
<debug> [1588357468.826845] (MTK) [mlan0] filtered by subsystem
<debug> [1588357468.836579] (Haier) [mlan0] filtered by subsystem
<debug> [1588357468.841591] (Quectel) [mlan0] filtered by drivers
<debug> [1588357468.855757] [plugin manager] task 3,mlan0: found '1' plugins to try
<debug> [1588357468.866775] [plugin manager] task 3,mlan0: will try with plugin 'Generic'
<debug> [1588357468.872751] [plugin manager) task 3,mlan0: started
<debug> [1588357468.885750] [plugin manager] task 3,mlan0: checking with plugin 'Generic'
<debug> [1588357468.890867] (Generic) [mlan0] probing deferred until result suggested
<debug> [1588357468.897716] [plugin manager] task 0,can0: deferring support check until result suggested
<debug> [1588357468.917199] [plugin manager] task 1: min probing time elapsed
<debug> [1588357468.922214] [plugin Manager] task 1: still 1 running probes (1 active): can1
<debug> [1588357468.927181] [plugin manager] task 2: min probing time elapsed
<debug> [1588357468.933755] [plugin Manager] task 2: still 1 running probes (1 active): eth0
<debug> [1588357468.956688] [plugin manager] task 3: min probing time elapsed
<debug> [1588357468.961680] [plugin Manager] task 3: still 2 running probes (2 active): uap0, mlan0
<debug> [1588357468.969092] [plugin manager] task 1,can1: deferring support check until result suggested
<debug> [1588357468.984291] [plugin manager] task 2,eth0: deferring support check until result suggested
<debug> [1588357468.996782] [plugin manager] task 3,uap0: deferring support check until result suggested
<debug> [1588357469.004651] [plugin manager] task 3,mlan0: deferring support check until result suggested
<debug> [1588357469.013354] (tty/ttyACM0): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1:1.0/tty/ttyACM0
<debug> [1588357469.021394] [filter] (tty/ttyACM0) port allowed
<debug> [1588357469.056751] (tty/ttyACM0): first port in device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357469.064053] [plugin manager] task 4: new support task for device: /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357469.072769] [plugin manager] task 4: port grabbed: ttyACM0
<debug> [1588357469.084258] [plugin manager] task 4,ttyACM0: new support task for port
<debug> [1588357469.091414] [plugin manager) task 4,ttyACM0: deferred until min wait time elapsed
<debug> [1588357469.097682] (tty/ttyACM1): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1:1.2/tty/ttyACM1
<debug> [1588357469.120015] [filter] (tty/ttyACM1) port allowed
<debug> [1588357469.136955] (tty/ttyACM1): additional port in device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357469.157073] [plugin manager] task 4: port grabbed: ttyACM1
<debug> [1588357469.162235] [plugin manager] task 4,ttyACM1: new support task for port
<debug> [1588357469.167218] [plugin manager) task 4,ttyACM1: deferred until min wait time elapsed
<debug> [1588357469.173923] (tty/ttyACM2): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1:1.4/tty/ttyACM2
<debug> [1588357469.181340] [filter] (tty/ttyACM2) port allowed
<debug> [1588357469.195844] (tty/ttyACM2): additional port in device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357469.224701] [plugin manager] task 4: port grabbed: ttyACM2
<debug> [1588357469.230952] [plugin manager] task 4,ttyACM2: new support task for port
<debug> [1588357469.237007] [plugin manager) task 4,ttyACM2: deferred until min wait time elapsed
<debug> [1588357469.243038] (tty/ttyACM3): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1:1.6/tty/ttyACM3
<debug> [1588357469.251085] [filter] (tty/ttyACM3) port allowed
<debug> [1588357469.275417] (tty/ttyACM3): additional port in device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357469.281787] [plugin manager] task 4: port grabbed: ttyACM3
<debug> [1588357469.287058] [plugin manager] task 4,ttyACM3: new support task for port
<debug> [1588357469.292226] [plugin manager) task 4,ttyACM3: deferred until min wait time elapsed
<debug> [1588357469.299250] (tty/ttyACM4): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1:1.8/tty/ttyACM4
<debug> [1588357469.345662] [filter] (tty/ttyACM4) port allowed
<debug> [1588357469.374342] (tty/ttyACM4): additional port in device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357469.386795] [plugin manager] task 4: port grabbed: ttyACM4
<debug> [1588357469.396413] [plugin manager] task 4,ttyACM4: new support task for port
<debug> [1588357469.411265] [plugin manager) task 4,ttyACM4: deferred until min wait time elapsed
<debug> [1588357469.426125] (tty/ttyACM5): adding device at sysfs path: /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1:1.10/tty/ttyACM5
<debug> [1588357469.450567] [filter] (tty/ttyACM5) port allowed
<debug> [1588357469.460173] (tty/ttyACM5): additional port in device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357469.472612] [plugin manager] task 4: port grabbed: ttyACM5
<debug> [1588357469.485024] [plugin manager] task 4,ttyACM5: new support task for port
<debug> [1588357469.494978] [plugin manager) task 4,ttyACM5: deferred until min wait time elapsed
<debug> [1588357470.573822] [plugin manager] task 4: min wait time elapsed
<debug> [1588357470.590844] (Sierra (legacy)) [ttyACM5] filtered by drivers
<debug> [1588357470.604323] (Pantech) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.616579] (Wavecom) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.626503] (SimTech) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.634470] (X22X) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.664310] (Ericsson MBM) [ttyACM5] filtered by udev tags
<debug> [1588357470.669491] (Thuraya) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.678104] (Novatel) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.684140] (Option High-Speed) [ttyACM5] filtered by drivers
<debug> [1588357470.693075] (Novatel LTE) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.703172] (Dell) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.724298] (Linktop) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.754248] (Nokia (Icera)) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.759437] (Samsung) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.764558] (Sierra) [ttyACM5] filtered by drivers
<debug> [1588357470.778978] (Longcheer) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.795534] (Huawei) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.822025] (AnyDATA) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.835036] (Motorola) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.843399] (Option) [ttyACM5] filtered by drivers
<debug> [1588357470.850318] (ZTE) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.855520] (Altair LTE) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.884334] (MTK) [ttyACM5] filtered by udev tags
<debug> [1588357470.896834] (Haier) [ttyACM5] filtered by vendor/product IDs
<debug> [1588357470.905369] (Quectel) [ttyACM5] filtered by drivers
<debug> [1588357470.911991] [plugin manager] task 4,ttyACM5: found '7' plugins to try
<debug> [1588357470.936658] [plugin manager] task 4,ttyACM5: will try with plugin 'u-blox'
<debug> [1588357470.948099] [plugin manager] task 4,ttyACM5: will try with plugin 'Via CBP7'
<debug> [1588357470.953718] [plugin manager] task 4,ttyACM5: will try with plugin 'Nokia'
<debug> [1588357470.960780] [plugin manager] task 4,ttyACM5: will try with plugin 'Cinterion'
<debug> [1588357470.968730] [plugin manager] task 4,ttyACM5: will try with plugin 'Telit'
<debug> [1588357470.994280] [plugin manager] task 4,ttyACM5: will try with plugin 'Iridium'
<debug> [1588357471.001448] [plugin manager] task 4,ttyACM5: will try with plugin 'Generic'
<debug> [1588357471.008255] [plugin manager) task 4,ttyACM5: started
<debug> [1588357471.024278] [plugin manager] task 4,ttyACM5: checking with plugin 'u-blox'
<debug> [1588357471.036760] (u-blox) [ttyACM5] probe required: 'at'
<debug> [1588357471.046973] (tty/ttyACM5) port probing finished: skipping for blacklisted port
<debug> [1588357471.054386] (Sierra (legacy)) [ttyACM4] filtered by drivers
<debug> [1588357471.064557] (Pantech) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.084236] (Wavecom) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.091820] (SimTech) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.104305] (X22X) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.129767] (Ericsson MBM) [ttyACM4] filtered by udev tags
<debug> [1588357471.144297] (Thuraya) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.150195] (Novatel) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.166561] (Option High-Speed) [ttyACM4] filtered by drivers
<debug> [1588357471.176791] (Novatel LTE) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.194271] (Dell) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.201687] (Linktop) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.210443] (Nokia (Icera)) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.219024] (Samsung) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.234239] (Sierra) [ttyACM4] filtered by drivers
<debug> [1588357471.242639] (Longcheer) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.247578] (Huawei) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.262076] (AnyDATA) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.270009] (Motorola) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.284292] (Option) [ttyACM4] filtered by drivers
<debug> [1588357471.289317] (ZTE) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.297430] (Altair LTE) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.305428] (MTK) [ttyACM4] filtered by udev tags
<debug> [1588357471.314116] (Haier) [ttyACM4] filtered by vendor/product IDs
<debug> [1588357471.341102] (Quectel) [ttyACM4] filtered by drivers
<debug> [1588357471.348648] [plugin manager] task 4,ttyACM4: found '7' plugins to try
<debug> [1588357471.356299] [plugin manager] task 4,ttyACM4: will try with plugin 'u-blox'
<debug> [1588357471.363500] [plugin manager] task 4,ttyACM4: will try with plugin 'Via CBP7'
<debug> [1588357471.384278] [plugin manager] task 4,ttyACM4: will try with plugin 'Nokia'
<debug> [1588357471.396894] [plugin manager] task 4,ttyACM4: will try with plugin 'Cinterion'
<debug> [1588357471.402847] [plugin manager] task 4,ttyACM4: will try with plugin 'Telit'
<debug> [1588357471.413428] [plugin manager] task 4,ttyACM4: will try with plugin 'Iridium'
<debug> [1588357471.424189] [plugin manager] task 4,ttyACM4: will try with plugin 'Generic'
<debug> [1588357471.445826] [plugin manager) task 4,ttyACM4: started
<debug> [1588357471.464285] [plugin manager] task 4,ttyACM4: checking with plugin 'u-blox'
<debug> [1588357471.473090] (u-blox) [ttyACM4] probe required: 'at'
<debug> [1588357471.491733] (tty/ttyACM4) port probing finished: skipping for blacklisted port
<debug> [1588357471.499932] (Sierra (legacy)) [ttyACM3] filtered by drivers
<debug> [1588357471.506223] (Pantech) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.513151] (Wavecom) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.519449] (SimTech) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.533932] (X22X) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.555858] (Ericsson MBM) [ttyACM3] filtered by udev tags
<debug> [1588357471.566491] (Thuraya) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.575886] (Novatel) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.585418] (Option High-Speed) [ttyACM3] filtered by drivers
<debug> [1588357471.590499] (Novatel LTE) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.597965] (Dell) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.625881] (Linktop) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.636257] (Nokia (Icera)) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.650599] (Samsung) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.657207] (Sierra) [ttyACM3] filtered by drivers
<debug> [1588357471.666737] (Longcheer) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.672738] (Huawei) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.685725] (AnyDATA) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.692227] (Motorola) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.708912] (Option) [ttyACM3] filtered by drivers
<debug> [1588357471.722067] (ZTE) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.729647] (Altair LTE) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.735605] (MTK) [ttyACM3] filtered by udev tags
<debug> [1588357471.747041] (Haier) [ttyACM3] filtered by vendor/product IDs
<debug> [1588357471.755746] (Quectel) [ttyACM3] filtered by drivers
<debug> [1588357471.762310] [plugin manager] task 4,ttyACM3: found '7' plugins to try
<debug> [1588357471.787995] [plugin manager] task 4,ttyACM3: will try with plugin 'u-blox'
<debug> [1588357471.798092] [plugin manager] task 4,ttyACM3: will try with plugin 'Via CBP7'
<debug> [1588357471.806395] [plugin manager] task 4,ttyACM3: will try with plugin 'Nokia'
<debug> [1588357471.811300] [plugin manager] task 4,ttyACM3: will try with plugin 'Cinterion'
<debug> [1588357471.818921] [plugin manager] task 4,ttyACM3: will try with plugin 'Telit'
<debug> [1588357471.827796] [plugin manager] task 4,ttyACM3: will try with plugin 'Iridium'
<debug> [1588357471.836516] [plugin manager] task 4,ttyACM3: will try with plugin 'Generic'
<debug> [1588357471.864288] [plugin manager) task 4,ttyACM3: started
<debug> [1588357471.876755] [plugin manager] task 4,ttyACM3: checking with plugin 'u-blox'
<debug> [1588357471.884834] (u-blox) [ttyACM3] probe required: 'at'
<debug> [1588357471.890770] (tty/ttyACM3) port probing finished: skipping for blacklisted port
<debug> [1588357471.898395] (Sierra (legacy)) [ttyACM2] filtered by drivers
<debug> [1588357471.907514] (Pantech) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357471.913782] (Wavecom) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357471.920379] (SimTech) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357471.945752] (X22X) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357471.956648] (Ericsson MBM) [ttyACM2] filtered by udev tags
<debug> [1588357471.966934] (Thuraya) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357471.976393] (Novatel) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357471.981188] (Option High-Speed) [ttyACM2] filtered by drivers
<debug> [1588357471.995699] (Novatel LTE) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.006851] (Dell) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.016527] (Linktop) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.026486] (Nokia (Icera)) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.035654] (Samsung) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.043710] (Sierra) [ttyACM2] filtered by drivers
<debug> [1588357472.064246] (Longcheer) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.073424] (Huawei) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.084373] (AnyDATA) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.090385] (Motorola) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.101010] (Option) [ttyACM2] filtered by drivers
<debug> [1588357472.108050] (ZTE) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.114936] (Altair LTE) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.130173] (MTK) [ttyACM2] filtered by udev tags
<debug> [1588357472.145006] (Haier) [ttyACM2] filtered by vendor/product IDs
<debug> [1588357472.160558] (Quectel) [ttyACM2] filtered by drivers
<debug> [1588357472.167695] [plugin manager] task 4,ttyACM2: found '7' plugins to try
<debug> [1588357472.191540] [plugin manager] task 4,ttyACM2: will try with plugin 'u-blox'
<debug> [1588357472.196533] [plugin manager] task 4,ttyACM2: will try with plugin 'Via CBP7'
<debug> [1588357472.205110] [plugin manager] task 4,ttyACM2: will try with plugin 'Nokia'
<debug> [1588357472.224298] [plugin manager] task 4,ttyACM2: will try with plugin 'Cinterion'
<debug> [1588357472.244281] [plugin manager] task 4,ttyACM2: will try with plugin 'Telit'
<debug> [1588357472.256365] [plugin manager] task 4,ttyACM2: will try with plugin 'Iridium'
<debug> [1588357472.262711] [plugin manager] task 4,ttyACM2: will try with plugin 'Generic'
<debug> [1588357472.270770] [plugin manager) task 4,ttyACM2: started
<debug> [1588357472.277854] [plugin manager] task 4,ttyACM2: checking with plugin 'u-blox'
<debug> [1588357472.285017] (u-blox) [ttyACM2] probe required: 'at'
<debug> [1588357472.295101] (tty/ttyACM2) launching port probing: 'at'
<debug> [1588357472.308152] (Sierra (legacy)) [ttyACM1] filtered by drivers
<debug> [1588357472.318487] (Pantech) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.325212] (Wavecom) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.334301] (SimTech) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.347641] (X22X) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.364320] (Ericsson MBM) [ttyACM1] filtered by udev tags
<debug> [1588357472.376628] (Thuraya) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.387382] (Novatel) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.393572] (Option High-Speed) [ttyACM1] filtered by drivers
<debug> [1588357472.414313] (Novatel LTE) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.424978] (Dell) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.434857] (Linktop) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.442413] (Nokia (Icera)) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.448506] (Samsung) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.454967] (Sierra) [ttyACM1] filtered by drivers
<debug> [1588357472.484308] (Longcheer) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.496831] (Huawei) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.505047] (AnyDATA) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.510143] (Motorola) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.517077] (Option) [ttyACM1] filtered by drivers
<debug> [1588357472.536299] (ZTE) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.554318] (Altair LTE) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.565001] (MTK) [ttyACM1] filtered by udev tags
<debug> [1588357472.574952] (Haier) [ttyACM1] filtered by vendor/product IDs
<debug> [1588357472.581137] (Quectel) [ttyACM1] filtered by drivers
<debug> [1588357472.596639] [plugin manager] task 4,ttyACM1: found '7' plugins to try
<debug> [1588357472.604728] [plugin manager] task 4,ttyACM1: will try with plugin 'u-blox'
<debug> [1588357472.614966] [plugin manager] task 4,ttyACM1: will try with plugin 'Via CBP7'
<debug> [1588357472.625932] [plugin manager] task 4,ttyACM1: will try with plugin 'Nokia'
<debug> [1588357472.634850] [plugin manager] task 4,ttyACM1: will try with plugin 'Cinterion'
<debug> [1588357472.644887] [plugin manager] task 4,ttyACM1: will try with plugin 'Telit'
<debug> [1588357472.657232] [plugin manager] task 4,ttyACM1: will try with plugin 'Iridium'
<debug> [1588357472.670177] [plugin manager] task 4,ttyACM1: will try with plugin 'Generic'
<debug> [1588357472.684340] [plugin manager) task 4,ttyACM1: started
<debug> [1588357472.704954] [plugin manager] task 4,ttyACM1: checking with plugin 'u-blox'
<debug> [1588357472.712978] (u-blox) [ttyACM1] probe required: 'at'
<debug> [1588357472.719926] (tty/ttyACM1) launching port probing: 'at'
<debug> [1588357472.734417] (Sierra (legacy)) [ttyACM0] filtered by drivers
<debug> [1588357472.748965] (Pantech) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.765490] (Wavecom) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.786163] (SimTech) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.791153] (X22X) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.804932] (Ericsson MBM) [ttyACM0] filtered by udev tags
<debug> [1588357472.824327] (Thuraya) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.832553] (Novatel) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.850426] (Option High-Speed) [ttyACM0] filtered by drivers
<debug> [1588357472.864958] (Novatel LTE) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.872785] (Dell) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.878921] (Linktop) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.884878] (Nokia (Icera)) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.904325] (Samsung) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.920291] (Sierra) [ttyACM0] filtered by drivers
<debug> [1588357472.935031] (Longcheer) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.940155] (Huawei) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.945217] (AnyDATA) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.952738] (Motorola) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.965002] (Option) [ttyACM0] filtered by drivers
<debug> [1588357472.974954] (ZTE) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.986249] (Altair LTE) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357472.992462] (MTK) [ttyACM0] filtered by udev tags
<debug> [1588357473.004996] (Haier) [ttyACM0] filtered by vendor/product IDs
<debug> [1588357473.011250] (Quectel) [ttyACM0] filtered by drivers
<debug> [1588357473.024934] [plugin manager] task 4,ttyACM0: found '7' plugins to try
<debug> [1588357473.054209] [plugin manager] task 4,ttyACM0: will try with plugin 'u-blox'
<debug> [1588357473.074597] [plugin manager] task 4,ttyACM0: will try with plugin 'Via CBP7'
<debug> [1588357473.095801] [plugin manager] task 4,ttyACM0: will try with plugin 'Nokia'
<debug> [1588357473.106579] [plugin manager] task 4,ttyACM0: will try with plugin 'Cinterion'
<debug> [1588357473.114248] [plugin manager] task 4,ttyACM0: will try with plugin 'Telit'
<debug> [1588357473.124237] [plugin manager] task 4,ttyACM0: will try with plugin 'Iridium'
<debug> [1588357473.146619] [plugin manager] task 4,ttyACM0: will try with plugin 'Generic'
<debug> [1588357473.164301] [plugin manager) task 4,ttyACM0: started
<debug> [1588357473.187716] [plugin manager] task 4,ttyACM0: checking with plugin 'u-blox'
<debug> [1588357473.198204] (u-blox) [ttyACM0] probe required: 'at'
<debug> [1588357473.211063] (tty/ttyACM0) launching port probing: 'at'
<debug> [1588357473.234455] [plugin manager] task 4: min probing time elapsed
<debug> [1588357473.250555] [plugin Manager] task 4: still 6 running probes (6 active): ttyACM5, ttyACM4, ttyACM3, ttyACM2, ttyACM1, ttyACM0
<debug> [1588357473.263224] [plugin manager] task 4,ttyACM5: found best plugin for port (u-blox)
<debug> [1588357473.275417] [plugin manager] task 4,ttyACM5: finished in '3.790401' seconds
<debug> [1588357473.294319] [plugin manager] task 4,ttyACM5: found best plugin: u-blox
<debug> [1588357473.307920] [plugin manager] task 4,ttyACM4: got suggested plugin (u-blox)
<debug> [1588357473.344311] [plugin manager] task 4,ttyACM3: got suggested plugin (u-blox)
<debug> [1588357473.362811] [plugin manager] task 4,ttyACM2: got suggested plugin (u-blox)
<debug> [1588357473.368576] [plugin manager] task 4,ttyACM1: got suggested plugin (u-blox)
<debug> [1588357473.374868] [plugin manager] task 4,ttyACM0: got suggested plugin (u-blox)
<debug> [1588357473.386626] [plugin Manager] task 4: still 5 running probes (5 active): ttyACM4, ttyACM3, ttyACM2, ttyACM1, ttyACM0
<debug> [1588357473.404482] [plugin manager] task 4,ttyACM4: found best plugin for port (u-blox)
<debug> [1588357473.444316] [plugin manager] task 4,ttyACM4: finished in '4.047902' seconds
<debug> [1588357473.470623] [plugin manager] task 4,ttyACM4: best plugin matches device reported one: u-blox
<debug> [1588357473.495425] [plugin Manager] task 4: still 4 running probes (4 active): ttyACM3, ttyACM2, ttyACM1, ttyACM0
<debug> [1588357473.507996] [plugin manager] task 4,ttyACM3: found best plugin for port (u-blox)
<debug> [1588357473.537713] [plugin manager] task 4,ttyACM3: finished in '4.250663' seconds
<debug> [1588357473.557682] [plugin manager] task 4,ttyACM3: best plugin matches device reported one: u-blox
<debug> [1588357473.598263] [plugin Manager] task 4: still 3 running probes (3 active): ttyACM2, ttyACM1, ttyACM0
<debug> [1588357473.618177] (ttyACM2) opening serial port...
<debug> [1588357473.646476] (ttyACM2): setting up baudrate: 57600
<debug> [1588357473.655158] (ttyACM2) device open count is 1 (open)
<debug> [1588357473.662817] (ttyACM2): port attributes not fully set
<debug> [1588357473.682444] (ttyACM1) opening serial port...
<debug> [1588357473.701978] (ttyACM1): setting up baudrate: 57600
<debug> [1588357473.716714] (ttyACM1) device open count is 1 (open)
<debug> [1588357473.723078] (ttyACM1): port attributes not fully set
<debug> [1588357473.733071] (ttyACM0) opening serial port...
<debug> [1588357473.753359] (ttyACM0): setting up baudrate: 57600
<debug> [1588357473.765908] (ttyACM0) device open count is 1 (open)
<debug> [1588357473.784253] (ttyACM0): port attributes not fully set
<debug> [1588357473.796008] (ttyACM2): <-- '<LF><LF><LF><LF>+UMWI: 0,1<CR><LF><CR><LF><CR><LF><CR><LF>W<CR><LF><CR><LF><CR><LF><CR><LF>+WI:'
<debug> [1588357473.809149] (ttyACM1): <-- '<LF><LF><LF><LF>+UMWI: 0,1<LF><LF><LF><LF><CR><LF><CR><LF>U<CR><LF><CR><LF><CR><LF>UMWI: 0,1<CR><LF><CR><LF>'
<debug> [1588357473.818656] (ttyACM0): <-- '<LF><LF><LF><LF>+UMWI: 0,1<CR><LF><CR><LF><CR><LF><CR><LF>+<CR><LF><CR><LF>+UM'
<debug> [1588357473.830418] (ttyACM2): --> 'AT<CR>'
<debug> [1588357473.857558] (ttyACM2): <-- 'AT<CR><CR><LF>OK<CR><LF>'
<debug> [1588357473.865414] (tty/ttyACM2) port is AT-capable
<debug> [1588357473.874253] [plugin manager] task 4,ttyACM2: found best plugin for port (u-blox)
<debug> [1588357473.880195] [plugin manager] task 4,ttyACM2: finished in '4.649241' seconds
<debug> [1588357473.887813] [plugin manager] task 4,ttyACM2: best plugin matches device reported one: u-blox
<debug> [1588357473.897936] [plugin Manager] task 4: still 2 running probes (2 active): ttyACM1, ttyACM0
<debug> [1588357473.904298] (ttyACM2) device open count is 0 (close)
<debug> [1588357473.909512] (ttyACM2) closing serial port...
<debug> [1588357473.919869] (ttyACM2) serial port closed
<debug> [1588357473.933632] (ttyACM2) forced to close port
<debug> [1588357473.944323] (ttyACM1): --> 'AT<CR>'
<debug> [1588357473.949820] (ttyACM0): --> 'AT<CR>'
<debug> [1588357473.955315] (ttyACM1): <-- 'AT<CR><CR><LF>OK<CR><LF>'
<debug> [1588357473.961961] (tty/ttyACM1) port is AT-capable
<debug> [1588357473.967786] [plugin manager] task 4,ttyACM1: found best plugin for port (u-blox)
<debug> [1588357473.972844] [plugin manager] task 4,ttyACM1: finished in '4.810613' seconds
<debug> [1588357473.977953] [plugin manager] task 4,ttyACM1: best plugin matches device reported one: u-blox
<debug> [1588357473.984284] [plugin Manager] task 4: still 1 running probes (1 active): ttyACM0
<debug> [1588357473.990459] (ttyACM1) device open count is 0 (close)
<debug> [1588357473.995547] (ttyACM1) closing serial port...
<debug> [1588357474.004839] (ttyACM1) serial port closed
<debug> [1588357474.011326] (ttyACM1) forced to close port
<debug> [1588357474.017856] (ttyACM0): <-- 'AT<CR><CR><LF>OK<CR><LF>'
<debug> [1588357474.023155] (tty/ttyACM0) port is AT-capable
<debug> [1588357474.028292] [plugin manager] task 4,ttyACM0: found best plugin for port (u-blox)
<debug> [1588357474.034495] [plugin manager] task 4,ttyACM0: finished in '4.950253' seconds
<debug> [1588357474.040582] [plugin manager] task 4,ttyACM0: best plugin matches device reported one: u-blox
<debug> [1588357474.047382] [plugin manager] task 4: no more ports to probe
<debug> [1588357474.052350] [plugin manager] task 4: finished in '4.988400' seconds
<info> [1588357474.058739] [device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1] creating modem with plugin 'u-blox' and '6' ports
<debug> [1588357474.087450] (tty/ttyACM5): port is blacklisted
<debug> [1588357474.098259] (ttyACM5) type 'ignored' claimed by /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357474.104926] (tty/ttyACM4): port is blacklisted
<debug> [1588357474.115884] (ttyACM4) type 'ignored' claimed by /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357474.123741] (tty/ttyACM3): port is blacklisted
<debug> [1588357474.142994] (ttyACM3) type 'ignored' claimed by /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357474.175051] (ttyACM2) type 'at' claimed by /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357474.184963] (ttyACM1) type 'at' claimed by /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357474.197587] (ttyACM0) type 'at' claimed by /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1
<debug> [1588357474.220220] (/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1) tty/ttyACM0 at (primary)
<debug> [1588357474.250774] (/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1) tty/ttyACM1 at (secondary)
<debug> [1588357474.281694] (/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1) tty/ttyACM0 data (primary)
<debug> [1588357474.312612] (ttyACM0) opening serial port...
<debug> [1588357474.378654] (ttyACM0): setting up baudrate: 57600
<debug> [1588357474.394912] (ttyACM0) device open count is 1 (open)
<debug> [1588357474.421842] (ttyACM0): running init sequence...
<debug> [1588357474.450488] (ttyACM0) device open count is 2 (open)
<debug> [1588357474.477002] (ttyACM0) device open count is 3 (open)
<info> [1588357474.529592] Modem for device '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1' successfully created
<debug> [1588357474.567122] (ttyACM0) device open count is 0 (close)
<debug> [1588357474.607325] (ttyACM0) closing serial port...
<debug> [1588357474.642259] (ttyACM0) serial port closed
<debug> [1588357474.686591] (ttyACM0) forced to close port
<debug> [1588357474.779116] loading current capabilities...
<debug> [1588357474.790451] (ttyACM0) device open count is 4 (open)
<debug> [1588357474.804421] (ttyACM0): --> 'ATE0<CR>'
<debug> [1588357474.853118] (ttyACM0): <-- 'ATE0<CR><CR><LF>OK<CR><LF>'
<debug> [1588357474.879057] (ttyACM0): --> 'ATV1<CR>'
<debug> [1588357474.897560] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357474.930989] (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1588357474.945811] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357474.969896] (ttyACM0): --> 'ATX4<CR>'
<debug> [1588357474.984758] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357475.009272] (ttyACM0): --> 'AT&C1<CR>'
<debug> [1588357475.029796] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357475.040602] (ttyACM0): --> 'ATE0<CR>'
<debug> [1588357475.054287] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357475.069897] (ttyACM0) device open count is 3 (close)
<debug> [1588357475.089180] (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1588357475.111697] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357475.137939] (ttyACM0) device open count is 2 (close)
<debug> [1588357475.159988] (ttyACM0): --> 'AT+GCAP<CR>'
<debug> [1588357475.175094] (ttyACM0): <-- '<CR><LF>+GCAP: +CGSM<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357475.182561] (ttyACM0) device open count is 3 (open)
<debug> [1588357475.193065] (ttyACM0) device open count is 2 (close)
<debug> [1588357475.201938] (ttyACM0): --> 'AT+WS46=?<CR>'
<debug> [1588357475.222617] (ttyACM0): <-- '<CR><LF>+WS46: (12,22,25,28,29,30,31)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357475.235857] Setting EPS network as supported
<debug> [1588357475.270547] Modem allows up to 1 bearers
<debug> [1588357475.280064] Creating bearer list (max: 1, max active: 1)
<debug> [1588357475.307888] loading manufacturer...
<debug> [1588357475.332474] (ttyACM0) device open count is 3 (open)
<debug> [1588357475.357513] (ttyACM0) device open count is 2 (close)
<debug> [1588357475.377320] (ttyACM0): --> 'AT+CGMI<CR>'
<debug> [1588357475.392375] (ttyACM0): <-- '<CR><LF>u-blox<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357475.407619] loaded manufacturer: u-blox
<debug> [1588357475.420297] loading model...
<debug> [1588357475.433051] (ttyACM0) device open count is 3 (open)
<debug> [1588357475.447104] (ttyACM0) device open count is 2 (close)
<debug> [1588357475.463718] (ttyACM0): --> 'AT+CGMM<CR>'
<debug> [1588357475.487322] (ttyACM0): <-- '<CR><LF>TOBY-R200<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357475.498529] loaded model: TOBY-R200
<debug> [1588357475.510994] loading revision...
<debug> [1588357475.524518] (ttyACM0) device open count is 3 (open)
<debug> [1588357475.543468] (ttyACM0) device open count is 2 (close)
<debug> [1588357475.562415] (ttyACM0): --> 'AT+CGMR<CR>'
<debug> [1588357475.578445] (ttyACM0): <-- '<CR><LF>30.33<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357475.592427] loaded revision: 30.33
<debug> [1588357475.611762] loading equipment identifier...
<debug> [1588357475.631409] (ttyACM0) device open count is 3 (open)
<debug> [1588357475.649292] (ttyACM0) device open count is 2 (close)
<debug> [1588357475.658338] (ttyACM0): --> 'AT+CGSN<CR>'
<debug> [1588357475.797723] (ttyACM0): <-- '<CR><LF>352848082591948<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357475.813414] loaded equipment identifier: 352848082591948
<debug> [1588357475.820235] loading device identifier...
<debug> [1588357475.834720] (ttyACM0) device open count is 3 (open)
<debug> [1588357475.848026] (ttyACM0) device open count is 2 (close)
<debug> [1588357475.862025] (ttyACM0): --> 'ATI<CR>'
<debug> [1588357475.991429] (ttyACM0): <-- '<CR><LF>TOBY-R200-02B-04<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.005025] Device ID source '0000154600001107TOBY-R200-02B-0435284808259194830.33TOBY-R200u-blox'
<debug> [1588357476.014532] Device ID 'd691e6c2214c10d436a8eeec0fc655aaf5b384a4'
<debug> [1588357476.023739] loaded device identifier: d691e6c2214c10d436a8eeec0fc655aaf5b384a4
<debug> [1588357476.033052] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.059433] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.080852] (ttyACM0): --> 'AT+URAT=?<CR>'
<debug> [1588357476.109334] (ttyACM0): <-- '<CR><LF>+URAT: (0-6),(0,2,3),(0,2,3)<CR><LF><CR><LF>OK<CR><LF>'
<warn> [1588357476.136189] couldn't load Supported Modes: 'Unexpected number of groups in +URAT=? response: 3'
<debug> [1588357476.150239] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.170066] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.182698] (ttyACM0): --> 'AT+UACT=?<CR>'
<debug> [1588357476.198383] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357476.207476] Got failure code 100: Unknown error
<debug> [1588357476.235248] loading supported IP families...
<debug> [1588357476.241674] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.250047] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.271081] (ttyACM0): --> 'AT+CGDCONT=?<CR>'
<debug> [1588357476.292204] (ttyACM0): <-- '<CR><LF>+CGDCONT: (1-11),"IP",,,0,(0-4),(0,1),(0,1),(0,1),(0,1)<CR><LF><CR><LF>+CGDCONT: (1-11),"IPV6",,,0,(0-4),(0,1),(0,1),(0,1),(0,1)<CR><LF><CR><LF>+CGDCONT: (1-11),"IPV4V6",,,0,(0-4),(0,1),(0,1),(0,1),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.302038] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.310838] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.321284] (ttyACM0): --> 'AT+CFUN?<CR>'
<debug> [1588357476.335667] (ttyACM0): <-- '<CR><LF>+CFUN: 1,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.345574] checking if unlock required...
<debug> [1588357476.357196] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.370124] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.380424] (ttyACM0): --> 'AT+CPIN?<CR>'
<debug> [1588357476.393892] (ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.401827] SIM is ready, and no need for the after SIM unlock step...
<debug> [1588357476.409218] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.414791] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.421219] (ttyACM0): --> 'AT+UPINCNT<CR>'
<debug> [1588357476.446982] (ttyACM0): <-- '<CR><LF>+UPINCNT: 3,10,10,10<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.457409] loading SIM identifier...
<debug> [1588357476.465122] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.474408] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.487045] (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
<debug> [1588357476.517887] (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"98880307000000042985"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.538629] loaded SIM identifier: 89883070000000409258
<debug> [1588357476.549726] loading IMSI...
<debug> [1588357476.575700] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.589340] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.599426] (ttyACM0): --> 'AT+CIMI<CR>'
<debug> [1588357476.621686] (ttyACM0): <-- '<CR><LF>234500011963214<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.637201] loaded IMSI: 234500011963214
<debug> [1588357476.649782] loading Operator ID...
<debug> [1588357476.656568] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.666057] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.676446] (ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR>'
<debug> [1588357476.707982] (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"00000002"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.714146] loading Operator Name...
<debug> [1588357476.720704] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.727087] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.732459] (ttyACM0): --> 'AT+CRSM=176,28486,0,0,17<CR>'
<debug> [1588357476.761690] (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"005477696C696FFFFFFFFFFFFFFFFFFFFF"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.770565] loading own numbers...
<debug> [1588357476.776883] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.787304] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.795471] (ttyACM0): --> 'AT+CNUM<CR>'
<debug> [1588357476.847349] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357476.854559] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.862099] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.869637] (ttyACM0): --> 'AT+UBANDSEL?<CR>'
<debug> [1588357476.885809] (ttyACM0): <-- '<CR><LF>+UBANDSEL: 1900,1700,850,700<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357476.899028] loading IMEI...
<debug> [1588357476.904377] (ttyACM0) device open count is 3 (open)
<debug> [1588357476.916908] (ttyACM0) device open count is 2 (close)
<debug> [1588357476.923525] (ttyACM0): --> 'AT+CGSN<CR>'
<debug> [1588357477.052854] (ttyACM0): <-- '<CR><LF>352848082591948<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.070144] loaded IMEI: 352848082591948
<debug> [1588357477.077469] loading enabled facility locks...
<debug> [1588357477.085227] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.094286] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.102024] (ttyACM0): --> 'AT+CLCK=?<CR>'
<debug> [1588357477.118328] (ttyACM0): <-- '<CR><LF>+CLCK: ("AO","OI","AI","IR","OX","AB","AG","AC","PS","PN","PU","PP","PC","SC","FD")<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.125274] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.130825] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.136261] (ttyACM0): --> 'AT+CLCK="SC",2<CR>'
<debug> [1588357477.146515] (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.153399] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.158875] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.164318] (ttyACM0): --> 'AT+CLCK="FD",2<CR>'
<debug> [1588357477.176218] (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.183065] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.189099] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.194410] (ttyACM0): --> 'AT+CLCK="PS",2<CR>'
<debug> [1588357477.207519] (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.216817] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.223381] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.230005] (ttyACM0): --> 'AT+CLCK="PN",2<CR>'
<debug> [1588357477.242212] (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.249680] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.257582] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.269304] (ttyACM0): --> 'AT+CLCK="PU",2<CR>'
<debug> [1588357477.307072] (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.318155] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.330686] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.344890] (ttyACM0): --> 'AT+CLCK="PP",2<CR>'
<debug> [1588357477.362464] (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.371089] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.380550] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.389131] (ttyACM0): --> 'AT+CLCK="PC",2<CR>'
<debug> [1588357477.399921] (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.410864] loading UE mode of operation for EPS...
<debug> [1588357477.419559] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.427218] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.433646] (ttyACM0): --> 'AT+CEMODE?<CR>'
<debug> [1588357477.444273] (ttyACM0): <-- '<CR><LF>+CEMODE: 1<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.452225] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.462393] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.470427] (ttyACM0): --> 'AT+CUSD=?<CR>'
<debug> [1588357477.480836] (ttyACM0): <-- '<CR><LF>+CUSD: (0-2)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.490038] (ttyACM0) device open count is 1 (close)
<debug> [1588357477.504951] (ttyACM0) device open count is 2 (open)
<debug> [1588357477.513706] (ttyACM0): --> 'AT+CNMI=?<CR>'
<debug> [1588357477.523596] (ttyACM0): <-- '<CR><LF>+CNMI: (0-2),(0-3),(0-3),(0-2),(0-1)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.529482] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.535008] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.541511] (ttyACM0): --> 'AT+CPMS=?<CR>'
<debug> [1588357477.551509] (ttyACM0): <-- '<CR><LF>+CPMS: ("BM","ME","SM","SR"),("ME","SM"),("BM","ME","SM","SR")<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.557638] Supported storages loaded:
<debug> [1588357477.562954] mem1 (list/read/delete) storages: 'bm, me, sm, sr'
<debug> [1588357477.569293] mem2 (write/send) storages: 'me, sm'
<debug> [1588357477.575591] mem3 (reception) storages: 'bm, me, sm, sr'
<debug> [1588357477.581197] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.587256] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.593810] (ttyACM0): --> 'AT+CPMS?<CR>'
<debug> [1588357477.604578] (ttyACM0): <-- '<CR><LF>+CPMS: "SR",0,10,"ME",0,100,"ME",0,100<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.611071] Current storages initialized:
<debug> [1588357477.618493] mem1 (list/read/delete) storages: 'sr'
<debug> [1588357477.625543] mem2 (write/send) storages: 'me'
<debug> [1588357477.630679] Current storages initialized
<debug> [1588357477.637927] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.645820] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.652028] (ttyACM0): --> 'ATH<CR>'
<debug> [1588357477.661795] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357477.668245] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.675980] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.682168] (ttyACM0): --> 'AT+CTZU=1<CR>'
<debug> [1588357477.691344] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357477.696875] (ttyACM0): --> 'AT+CCLK?<CR>'
<debug> [1588357477.707643] (ttyACM0): <-- '<CR><LF>+CCLK: "20/05/01,13:24:33-20"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.715133] (ttyACM0) device open count is 3 (open)
<debug> [1588357477.720370] (ttyACM0) device open count is 2 (close)
<debug> [1588357477.726311] (ttyACM0): --> 'AT+CESQ=?<CR>'
<debug> [1588357477.737806] (ttyACM0): <-- '<CR><LF>+CESQ: (0-63,99),(0-7,99),(0-96,255),(0-49,255),(0-34,255),(0-97,255)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357477.745848] (ttyACM0) device open count is 1 (close)
<debug> [1588357477.751012] Couldn't initialize interface: 'OMA not supported'
<debug> [1588357477.759169] Couldn't initialize interface: 'Firmware interface not available'
<info> [1588357477.767484] Modem: state changed (unknown -> disabled)
<debug> [1588357477.774501] modem properly initialized
<debug> [1588357477.817060] [device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1] exported modem at path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [1588357477.826189] [device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1] plugin: u-blox
<debug> [1588357477.833363] [device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1] vid:pid: 0x1546:0x1107
<debug> [1588357477.840598] (ttyACM0) device open count is 0 (close)
<debug> [1588357477.847982] (ttyACM0) closing serial port...
<debug> [1588357477.859528] (ttyACM0) serial port closed
<info> [1588357477.964481] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
<debug> [1588357477.983485] Skipping modem initialization: device hotplugged
<debug> [1588357477.999908] (ttyACM0) opening serial port...
<debug> [1588357478.013089] (ttyACM0): setting up baudrate: 57600
<debug> [1588357478.025260] (ttyACM0) device open count is 1 (open)
<debug> [1588357478.038673] (ttyACM0): running init sequence...
<debug> [1588357478.046641] (ttyACM1) opening serial port...
<debug> [1588357478.059896] (ttyACM1): setting up baudrate: 57600
<debug> [1588357478.075819] (ttyACM1) device open count is 1 (open)
<debug> [1588357478.084024] (ttyACM1): running init sequence...
<debug> [1588357478.092131] Flashing primary AT port before enabling...
<debug> [1588357478.108629] (ttyACM0): port attributes not fully set
<debug> [1588357478.126867] (ttyACM0): --> 'ATE0<CR>'
<debug> [1588357478.138439] (ttyACM1): --> 'ATE0<CR>'
<debug> [1588357478.150001] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357478.157543] (ttyACM1): <-- 'ATE0<CR><CR><LF>OK<CR><LF>'
<debug> [1588357478.169761] (ttyACM0): --> 'ATV1<CR>'
<debug> [1588357478.185070] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357478.192938] (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1588357478.211548] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357478.220203] (ttyACM0): --> 'ATX4<CR>'
<debug> [1588357478.234357] (ttyACM0) device open count is 2 (open)
<debug> [1588357478.241212] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357478.250410] (ttyACM0): --> 'AT&C1<CR>'
<debug> [1588357478.270700] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357478.278955] (ttyACM0): --> 'AT+CFUN?<CR>'
<debug> [1588357478.304155] (ttyACM0): <-- '<CR><LF>+CFUN: 1,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357478.310121] No need to change power state: already in 'on' power state
<debug> [1588357478.320015] Checking if SIM was swapped...
<debug> [1588357478.326874] loading SIM identifier...
<debug> [1588357478.334030] (ttyACM0) device open count is 3 (open)
<debug> [1588357478.340222] (ttyACM0) device open count is 2 (close)
<debug> [1588357478.348296] (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
<debug> [1588357478.381062] (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"98880307000000042985"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357478.388151] loaded SIM identifier: 89883070000000409258
<debug> [1588357478.397817] (ttyACM0) device open count is 3 (open)
<debug> [1588357478.408967] (ttyACM0) device open count is 2 (close)
<debug> [1588357478.449813] (ttyACM0): --> 'AT+IFC=?<CR>'
<debug> [1588357478.460035] (ttyACM0): <-- '<CR><LF>+IFC: (0-2),(0-2)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357478.466275] TE supports no flow control
<debug> [1588357478.473934] TE supports XON/XOFF flow control
<debug> [1588357478.481475] TE supports RTS/CTS flow control
<debug> [1588357478.489620] TA supports no flow control
<debug> [1588357478.497731] TA supports XON/XOFF flow control
<debug> [1588357478.508376] TA supports RTS/CTS flow control
<debug> [1588357478.532593] (ttyACM0) device open count is 3 (open)
<debug> [1588357478.553492] (ttyACM0) device open count is 4 (open)
<debug> [1588357478.569179] (ttyACM0) device open count is 3 (close)
<debug> [1588357478.584196] (ttyACM0): --> 'AT+IFC=2,2<CR>'
<debug> [1588357478.618694] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357478.624780] (ttyACM0) device open count is 2 (close)
<debug> [1588357478.644186] (ttyACM0): --> 'AT+CSCS=?<CR>'
<debug> [1588357478.664041] (ttyACM0): <-- '<CR><LF>+CSCS: ("IRA","GSM","PCCP437","8859-1","UCS2","HEX")<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357478.689979] (ttyACM0) device open count is 3 (open)
<debug> [1588357478.700426] (ttyACM0) device open count is 2 (close)
<debug> [1588357478.713221] (ttyACM0): --> 'AT+CSCS="UCS2"<CR>'
<debug> [1588357478.732447] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357478.739761] (ttyACM0) device open count is 3 (open)
<debug> [1588357478.760742] (ttyACM0) device open count is 2 (close)
<debug> [1588357478.776757] (ttyACM0): --> 'AT+CSCS?<CR>'
<debug> [1588357478.799588] (ttyACM0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357478.808295] Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
<debug> [1588357478.819117] Checking indicator support...
<debug> [1588357478.831554] (ttyACM0) device open count is 3 (open)
<debug> [1588357478.841885] (ttyACM0) device open count is 2 (close)
<info> [1588357478.851191] Simple connect started...
<debug> [1588357478.859980] PIN: unspecified
<debug> [1588357478.868156] Operator ID: unspecified
<debug> [1588357478.882140] Allowed roaming: yes
<debug> [1588357478.893088] APN: super
<debug> [1588357478.915954] IP family: ipv4v6
<debug> [1588357478.922826] Allowed authentication: unspecified
<debug> [1588357478.930855] User: unspecified
<debug> [1588357478.937326] Password: unspecified
<debug> [1588357478.943809] Number: *99#
<info> [1588357478.949271] Simple connect state (4/8): Wait to get fully enabled
<debug> [1588357478.986944] (ttyACM0): --> 'AT+CIND=?<CR>'
<debug> [1588357478.998922] (ttyACM0): <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",(0-5)),("service",(0-1)),("sounder",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("gprs",(0-2)),("callsetup",(0-3)),("callheld",(0-1)),("simind",(0-2))<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357479.005338] Modem supports signal quality indications via CIND at index '2'(min: 0, max: 5)
<debug> [1588357479.011980] Modem supports roaming indications via CIND at index '7'
<debug> [1588357479.018750] Modem supports service indications via CIND at index '3'
<debug> [1588357479.029316] (ttyACM0) device open count is 3 (open)
<debug> [1588357479.040910] (ttyACM0) device open count is 2 (close)
<debug> [1588357479.049384] (ttyACM0): --> 'AT+CMER=?<CR>'
<debug> [1588357479.069938] (ttyACM0): <-- '<CR><LF>+CMER: (0-3),(0),(0),(0-2),(0-1)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357479.085326] Supported +CMER modes: discard-urcs, discard-urcs-if-link-reserved, buffer-urcs-if-link-reserved, forward-urcs
<debug> [1588357479.093866] Supported +CMER indication settings: disable, enable-not-caused-by-cind, enable-all
<debug> [1588357479.107895] +CMER enable mode: forward-urcs
<debug> [1588357479.126050] +CMER disable mode: discard-urcs
<debug> [1588357479.145343] +CMER indication setting: enable-not-caused-by-cind
<debug> [1588357479.154675] (ttyACM0) Setting 3GPP unsolicited events handlers
<debug> [1588357479.167467] (ttyACM1) Setting 3GPP unsolicited events handlers
<debug> [1588357479.179613] (ttyACM0) device open count is 3 (open)
<debug> [1588357479.192617] (ttyACM0) device open count is 2 (close)
<debug> [1588357479.201646] (ttyACM0): --> 'AT+CMER=3,0,0,1<CR>'
<debug> [1588357479.219765] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.232226] (ttyACM1) device open count is 2 (open)
<debug> [1588357479.243592] (ttyACM0) device open count is 1 (close)
<debug> [1588357479.255957] (ttyACM1): --> 'AT+CMER=3,0,0,1<CR>'
<debug> [1588357479.269206] (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.278775] (ttyACM0) setting up 3GPP unsolicited registration messages handlers
<debug> [1588357479.291003] (ttyACM1) setting up 3GPP unsolicited registration messages handlers
<debug> [1588357479.306055] (ttyACM1) device open count is 1 (close)
<debug> [1588357479.325094] (ttyACM0) device open count is 2 (open)
<debug> [1588357479.356407] (ttyACM0): --> 'AT+CREG=2<CR>'
<debug> [1588357479.366335] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.371777] (ttyACM1) device open count is 2 (open)
<debug> [1588357479.396952] (ttyACM0) device open count is 1 (close)
<debug> [1588357479.402555] (ttyACM1): --> 'AT+CREG=2<CR>'
<debug> [1588357479.412063] (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.419072] (ttyACM0) device open count is 2 (open)
<debug> [1588357479.427010] (ttyACM1) device open count is 1 (close)
<debug> [1588357479.435752] (ttyACM0): --> 'AT+CGREG=2<CR>'
<debug> [1588357479.473369] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.483407] (ttyACM1) device open count is 2 (open)
<debug> [1588357479.517994] (ttyACM0) device open count is 1 (close)
<debug> [1588357479.540725] (ttyACM1): --> 'AT+CGREG=2<CR>'
<debug> [1588357479.553212] (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.566731] (ttyACM0) device open count is 2 (open)
<debug> [1588357479.586674] (ttyACM1) device open count is 1 (close)
<debug> [1588357479.603632] (ttyACM0): --> 'AT+CEREG=2<CR>'
<debug> [1588357479.626206] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.651816] (ttyACM1) device open count is 2 (open)
<debug> [1588357479.667682] (ttyACM0) device open count is 1 (close)
<debug> [1588357479.680056] (ttyACM1): --> 'AT+CEREG=2<CR>'
<debug> [1588357479.696960] (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.706020] Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
<debug> [1588357479.722465] (ttyACM0) Setting unsolicited result code handlers
<debug> [1588357479.732619] (ttyACM1) Setting unsolicited result code handlers
<debug> [1588357479.746048] (ttyACM1) device open count is 1 (close)
<debug> [1588357479.755630] (ttyACM0) device open count is 2 (open)
<debug> [1588357479.764412] (ttyACM0): --> 'AT+CUSD=1<CR>'
<debug> [1588357479.775638] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357479.783497] Modem has location capabilities, enabling the Location interface...
<debug> [1588357479.793752] Need to enable the following location sources: '3gpp-lac-ci'
<debug> [1588357479.801691] Running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes')
<debug> [1588357479.810688] (ttyACM0) device open count is 3 (open)
<debug> [1588357479.816563] (ttyACM0) device open count is 2 (close)
<debug> [1588357479.824912] Modem has messaging capabilities, enabling the Messaging interface...
<debug> [1588357479.842887] (ttyACM0) device open count is 3 (open)
<debug> [1588357479.851376] (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1588357479.864413] (ttyACM0): <-- '<CR><LF>+CREG: 2,3<CR><LF><CR><LF>OK<CR><LF>'
<info> [1588357479.882980] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> denied)
<debug> [1588357479.892287] Will start keeping track of state for subsystem '3gpp'
<debug> [1588357479.903468] (ttyACM0) device open count is 4 (open)
<debug> [1588357479.924093] (ttyACM0) device open count is 3 (close)
<debug> [1588357479.944729] (ttyACM0): --> 'AT+CMGF=?<CR>'
<debug> [1588357479.966934] (ttyACM0): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357479.980474] (ttyACM0) device open count is 4 (open)
<debug> [1588357479.990231] (ttyACM0) device open count is 3 (close)
<debug> [1588357480.000015] (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1588357480.023736] (ttyACM0): <-- '<CR><LF>+CGREG: 2,3<CR><LF><CR><LF>OK<CR><LF>'
<info> [1588357480.032652] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (denied -> unknown)
<debug> [1588357480.052590] (ttyACM0) device open count is 4 (open)
<debug> [1588357480.067885] (ttyACM0) device open count is 3 (close)
<debug> [1588357480.085123] (ttyACM0): --> 'AT+CMGF=0<CR>'
<debug> [1588357480.112283] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357480.205519] Successfully set preferred SMS mode: 'PDU'
<debug> [1588357480.216417] (ttyACM0) device open count is 4 (open)
<debug> [1588357480.228200] (ttyACM0) device open count is 3 (close)
<debug> [1588357480.243500] (ttyACM0): --> 'AT+CEREG?<CR>'
<debug> [1588357480.263447] (ttyACM0): <-- '<CR><LF>+CEREG: 2,0<CR><LF><CR><LF>OK<CR><LF>'
<info> [1588357480.274588] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> idle)
<debug> [1588357480.292128] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.307323] (ttyACM0): --> 'AT+CPMS="SR","ME","ME"<CR>'
<debug> [1588357480.326213] (ttyACM0): <-- '<CR><LF>+CPMS: 0,10,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357480.333328] Listing SMS parts in storage 'bm'
<debug> [1588357480.343006] Locking SMS storages to: mem1 (BM), mem2 (none)...
<debug> [1588357480.361302] (ttyACM0) device open count is 3 (open)
<debug> [1588357480.389815] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.411796] (ttyACM0): --> 'AT+CPMS="BM"<CR>'
<debug> [1588357480.425599] (ttyACM0): <-- '<CR><LF>+CPMS: 0,10,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357480.432630] (ttyACM0) device open count is 3 (open)
<debug> [1588357480.443035] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.460710] (ttyACM0): --> 'AT+CMGL=4<CR>'
<debug> [1588357480.477809] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357480.486652] Listing SMS parts in storage 'me'
<debug> [1588357480.495846] Locking SMS storages to: mem1 (ME), mem2 (none)...
<debug> [1588357480.504709] (ttyACM0) device open count is 3 (open)
<debug> [1588357480.514098] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.531699] (ttyACM0): --> 'AT+CPMS="ME"<CR>'
<debug> [1588357480.545010] (ttyACM0): <-- '<CR><LF>+CPMS: 0,100,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357480.552239] (ttyACM0) device open count is 3 (open)
<debug> [1588357480.557766] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.563847] (ttyACM0): --> 'AT+CMGL=4<CR>'
<debug> [1588357480.575289] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357480.582145] Listing SMS parts in storage 'sm'
<debug> [1588357480.587353] Locking SMS storages to: mem1 (SM), mem2 (none)...
<debug> [1588357480.595434] (ttyACM0) device open count is 3 (open)
<debug> [1588357480.601984] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.612081] (ttyACM0): --> 'AT+CPMS="SM"<CR>'
<debug> [1588357480.627817] (ttyACM0): <-- '<CR><LF>+CPMS: 0,40,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357480.633537] (ttyACM0) device open count is 3 (open)
<debug> [1588357480.640043] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.647482] (ttyACM0): --> 'AT+CMGL=4<CR>'
<debug> [1588357480.678453] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357480.685703] Listing SMS parts in storage 'sr'
<debug> [1588357480.692754] Locking SMS storages to: mem1 (SR), mem2 (none)...
<debug> [1588357480.711210] (ttyACM0) device open count is 3 (open)
<debug> [1588357480.725939] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.739240] (ttyACM0): --> 'AT+CPMS="SR"<CR>'
<debug> [1588357480.751025] (ttyACM0): <-- '<CR><LF>+CPMS: 0,10,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357480.757134] (ttyACM0) device open count is 3 (open)
<debug> [1588357480.763421] (ttyACM0) device open count is 2 (close)
<debug> [1588357480.772773] (ttyACM0): --> 'AT+CMGL=4<CR>'
<debug> [1588357480.792336] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357480.799700] (ttyACM0) Setting messaging unsolicited events handlers
<debug> [1588357480.806694] (ttyACM1) Setting messaging unsolicited events handlers
<debug> [1588357480.815803] (ttyACM0) device open count is 1 (close)
<debug> [1588357480.825163] (ttyACM0) Enabling messaging unsolicited events on primary port
<debug> [1588357480.833711] (ttyACM0) device open count is 2 (open)
<debug> [1588357480.840005] (ttyACM0): --> 'AT+CNMI=2,1,2,1,0<CR>'
<debug> [1588357480.850015] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357480.860657] (ttyACM0) Messaging unsolicited events enabled on primary
<debug> [1588357480.867197] (ttyACM1) Enabling messaging unsolicited events on secondary port
<debug> [1588357480.873070] (ttyACM1) device open count is 2 (open)
<debug> [1588357480.884963] (ttyACM0) device open count is 1 (close)
<debug> [1588357480.891579] (ttyACM1): --> 'AT+CNMI=2,1,2,1,0<CR>'
<debug> [1588357480.901067] (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357480.908658] (ttyACM1) Messaging unsolicited events enabled on secondary
<debug> [1588357480.915502] Modem has voice capabilities, enabling the Voice interface...
<debug> [1588357480.926199] (ttyACM0) Setting voice unsolicited events handlers
<debug> [1588357480.933623] (ttyACM1) Setting voice unsolicited events handlers
<debug> [1588357480.941303] (ttyACM1) device open count is 1 (close)
<debug> [1588357480.951644] (ttyACM0) device open count is 2 (open)
<debug> [1588357480.960634] (ttyACM0): --> 'AT+CLIP=1<CR>'
<debug> [1588357480.980813] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357480.987144] (ttyACM0): --> 'AT+CRC=1<CR>'
<debug> [1588357480.998692] (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1588357481.007449] Modem has time capabilities, enabling the Time interface...
<debug> [1588357481.015313] (ttyACM0) device open count is 1 (close)
<debug> [1588357481.025390] Modem has extended signal reporting capabilities, enabling the Signal interface...
<debug> [1588357481.041796] Extended signal information reporting disabled (rate: 0 seconds)
<info> [1588357481.052359] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
<info> [1588357481.065729] Simple connect state (5/8): Register
<debug> [1588357481.077205] Launching automatic network registration...
<debug> [1588357481.087070] (ttyACM0) device open count is 2 (open)
<debug> [1588357481.095049] Running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes')
<debug> [1588357481.108007] (ttyACM0) device open count is 3 (open)
<debug> [1588357481.113469] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357481.129424] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357481.135729] Got failure code 100: Unknown error
<debug> [1588357481.142018] (ttyACM0) device open count is 2 (close)
<debug> [1588357481.149733] (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1588357481.164790] (ttyACM0): <-- '<CR><LF>+CREG: 2,3<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357481.174350] (ttyACM0) device open count is 3 (open)
<debug> [1588357481.183297] (ttyACM0) device open count is 2 (close)
<debug> [1588357481.206714] (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1588357481.218361] (ttyACM0): <-- '<CR><LF>+CGREG: 2,3<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357481.236791] (ttyACM0) device open count is 3 (open)
<debug> [1588357481.250569] (ttyACM0) device open count is 2 (close)
<debug> [1588357481.260393] (ttyACM0): --> 'AT+CEREG?<CR>'
<debug> [1588357481.284636] (ttyACM0): <-- '<CR><LF>+CEREG: 2,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357481.294066] Initial 3GPP registration checks finished
<debug> [1588357481.304960] (ttyACM0) device open count is 1 (close)
<info> [1588357481.519719] Simple connect started...
<debug> [1588357481.541590] PIN: unspecified
<debug> [1588357481.549233] Operator ID: unspecified
<debug> [1588357481.556469] Allowed roaming: yes
<debug> [1588357481.562880] APN: super
<debug> [1588357481.571953] IP family: ipv4
<debug> [1588357481.579202] Allowed authentication: unspecified
<debug> [1588357481.589618] User: unspecified
<debug> [1588357481.597553] Password: unspecified
<debug> [1588357481.603362] Number: *99#
<info> [1588357481.612256] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357481.622025] Simple connect state (5/8): Register
<debug> [1588357481.630760] Launching automatic network registration...
<debug> [1588357481.636793] (ttyACM0) device open count is 2 (open)
<debug> [1588357481.642284] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357481.652062] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357481.659122] Got failure code 100: Unknown error
<debug> [1588357481.666013] (ttyACM0) device open count is 1 (close)
<info> [1588357481.696503] Simple connect started...
<debug> [1588357481.704026] PIN: unspecified
<debug> [1588357481.718672] Operator ID: unspecified
<debug> [1588357481.730430] Allowed roaming: yes
<debug> [1588357481.737154] APN: super
<debug> [1588357481.743265] IP family: ipv6
<debug> [1588357481.757121] Allowed authentication: unspecified
<debug> [1588357481.762665] User: unspecified
<debug> [1588357481.772777] Password: unspecified
<debug> [1588357481.794056] Number: *99#
<info> [1588357481.804405] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357481.810960] Simple connect state (5/8): Register
<debug> [1588357481.816437] Launching automatic network registration...
<debug> [1588357481.821775] (ttyACM0) device open count is 2 (open)
<debug> [1588357481.828608] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357481.839483] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357481.846072] Got failure code 100: Unknown error
<debug> [1588357481.852414] (ttyACM0) device open count is 1 (close)
<info> [1588357482.300017] Simple connect started...
<debug> [1588357482.315888] PIN: unspecified
<debug> [1588357482.331959] Operator ID: unspecified
<debug> [1588357482.337226] Allowed roaming: yes
<debug> [1588357482.343030] APN: super
<debug> [1588357482.349535] IP family: ipv4v6
<debug> [1588357482.357205] Allowed authentication: unspecified
<debug> [1588357482.371247] User: unspecified
<debug> [1588357482.385113] Password: unspecified
<debug> [1588357482.402498] Number: *99#
<info> [1588357482.408550] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357482.414141] Simple connect state (5/8): Register
<debug> [1588357482.421052] Launching automatic network registration...
<debug> [1588357482.427450] (ttyACM0) device open count is 2 (open)
<debug> [1588357482.433761] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357482.444774] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357482.451539] Got failure code 100: Unknown error
<debug> [1588357482.458752] (ttyACM0) device open count is 1 (close)
<info> [1588357482.470657] Simple connect started...
<debug> [1588357482.475808] PIN: unspecified
<debug> [1588357482.482066] Operator ID: unspecified
<debug> [1588357482.489035] Allowed roaming: yes
<debug> [1588357482.494096] APN: super
<debug> [1588357482.499318] IP family: ipv4
<debug> [1588357482.508542] Allowed authentication: unspecified
<debug> [1588357482.514952] User: unspecified
<debug> [1588357482.520015] Password: unspecified
<debug> [1588357482.527122] Number: *99#
<info> [1588357482.532970] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357482.538449] Simple connect state (5/8): Register
<debug> [1588357482.558685] Launching automatic network registration...
<debug> [1588357482.569614] (ttyACM0) device open count is 2 (open)
<debug> [1588357482.578574] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357482.590684] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357482.596536] Got failure code 100: Unknown error
<debug> [1588357482.610251] (ttyACM0) device open count is 1 (close)
<info> [1588357482.884721] Simple connect started...
<debug> [1588357482.905205] PIN: unspecified
<debug> [1588357482.913486] Operator ID: unspecified
<debug> [1588357482.921765] Allowed roaming: yes
<debug> [1588357482.932359] APN: super
<debug> [1588357482.940100] IP family: ipv6
<debug> [1588357482.949620] Allowed authentication: unspecified
<debug> [1588357482.959520] User: unspecified
<debug> [1588357482.971839] Password: unspecified
<debug> [1588357482.981861] Number: *99#
<info> [1588357482.991945] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357483.001514] Simple connect state (5/8): Register
<debug> [1588357483.010180] Launching automatic network registration...
<debug> [1588357483.023128] (ttyACM0) device open count is 2 (open)
<debug> [1588357483.031465] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357483.044104] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357483.050536] Got failure code 100: Unknown error
<debug> [1588357483.057069] (ttyACM0) device open count is 1 (close)
<info> [1588357483.344272] Simple connect started...
<debug> [1588357483.359839] PIN: unspecified
<debug> [1588357483.365767] Operator ID: unspecified
<debug> [1588357483.371605] Allowed roaming: yes
<debug> [1588357483.376710] APN: super
<debug> [1588357483.394445] IP family: ipv4v6
<debug> [1588357483.406040] Allowed authentication: unspecified
<debug> [1588357483.411349] User: unspecified
<debug> [1588357483.416952] Password: unspecified
<debug> [1588357483.424076] Number: *99#
<info> [1588357483.429625] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357483.437352] Simple connect state (5/8): Register
<debug> [1588357483.446237] Launching automatic network registration...
<debug> [1588357483.453601] (ttyACM0) device open count is 2 (open)
<debug> [1588357483.459114] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357483.472863] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357483.480547] Got failure code 100: Unknown error
<debug> [1588357483.491329] (ttyACM0) device open count is 1 (close)
<info> [1588357483.624964] Simple connect started...
<debug> [1588357483.641903] PIN: unspecified
<debug> [1588357483.649714] Operator ID: unspecified
<debug> [1588357483.656171] Allowed roaming: yes
<debug> [1588357483.661905] APN: super
<debug> [1588357483.667023] IP family: ipv4
<debug> [1588357483.673162] Allowed authentication: unspecified
<debug> [1588357483.679615] User: unspecified
<debug> [1588357483.684759] Password: unspecified
<debug> [1588357483.689894] Number: *99#
<info> [1588357483.696934] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357483.703206] Simple connect state (5/8): Register
<debug> [1588357483.708639] Launching automatic network registration...
<debug> [1588357483.713883] (ttyACM0) device open count is 2 (open)
<debug> [1588357483.721557] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357483.733943] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357483.739699] Got failure code 100: Unknown error
<debug> [1588357483.745743] (ttyACM0) device open count is 1 (close)
<info> [1588357483.759167] Simple connect started...
<debug> [1588357483.765901] PIN: unspecified
<debug> [1588357483.771230] Operator ID: unspecified
<debug> [1588357483.776328] Allowed roaming: yes
<debug> [1588357483.782653] APN: super
<debug> [1588357483.789397] IP family: ipv6
<debug> [1588357483.794764] Allowed authentication: unspecified
<debug> [1588357483.800055] User: unspecified
<debug> [1588357483.806589] Password: unspecified
<debug> [1588357483.812740] Number: *99#
<info> [1588357483.819068] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357483.826790] Simple connect state (5/8): Register
<debug> [1588357483.833309] Launching automatic network registration...
<debug> [1588357483.839632] (ttyACM0) device open count is 2 (open)
<debug> [1588357483.845369] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357483.856204] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357483.863259] Got failure code 100: Unknown error
<debug> [1588357483.873819] (ttyACM0) device open count is 1 (close)
<info> [1588357484.267448] Simple connect started...
<debug> [1588357484.287044] PIN: unspecified
<debug> [1588357484.295491] Operator ID: unspecified
<debug> [1588357484.301533] Allowed roaming: yes
<debug> [1588357484.306688] APN: super
<debug> [1588357484.311933] IP family: ipv4v6
<debug> [1588357484.318250] Allowed authentication: unspecified
<debug> [1588357484.324478] User: unspecified
<debug> [1588357484.330865] Password: unspecified
<debug> [1588357484.336206] Number: *99#
<info> [1588357484.343939] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357484.350279] Simple connect state (5/8): Register
<debug> [1588357484.355631] Launching automatic network registration...
<debug> [1588357484.361076] (ttyACM0) device open count is 2 (open)
<debug> [1588357484.369546] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357484.381317] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357484.389590] Got failure code 100: Unknown error
<debug> [1588357484.396081] (ttyACM0) device open count is 1 (close)
<info> [1588357484.415881] Simple connect started...
<debug> [1588357484.424321] PIN: unspecified
<debug> [1588357484.435321] Operator ID: unspecified
<debug> [1588357484.448459] Allowed roaming: yes
<debug> [1588357484.474502] APN: super
<debug> [1588357484.485166] IP family: ipv4
<debug> [1588357484.493403] Allowed authentication: unspecified
<debug> [1588357484.503660] User: unspecified
<debug> [1588357484.513012] Password: unspecified
<debug> [1588357484.519057] Number: *99#
<info> [1588357484.527447] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357484.536234] Simple connect state (5/8): Register
<debug> [1588357484.544394] Launching automatic network registration...
<debug> [1588357484.552560] (ttyACM0) device open count is 2 (open)
<debug> [1588357484.561500] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357484.574504] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357484.584178] Got failure code 100: Unknown error
<debug> [1588357484.593800] (ttyACM0) device open count is 1 (close)
<info> [1588357484.667966] Simple connect started...
<debug> [1588357484.681987] PIN: unspecified
<debug> [1588357484.688729] Operator ID: unspecified
<debug> [1588357484.695270] Allowed roaming: yes
<debug> [1588357484.700760] APN: super
<debug> [1588357484.706300] IP family: ipv6
<debug> [1588357484.715790] Allowed authentication: unspecified
<debug> [1588357484.722948] User: unspecified
<debug> [1588357484.731447] Password: unspecified
<debug> [1588357484.741105] Number: *99#
<info> [1588357484.759398] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357484.774283] Simple connect state (5/8): Register
<debug> [1588357484.781284] Launching automatic network registration...
<debug> [1588357484.786746] (ttyACM0) device open count is 2 (open)
<debug> [1588357484.792808] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357484.804352] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357484.810958] Got failure code 100: Unknown error
<debug> [1588357484.823677] (ttyACM0) device open count is 1 (close)
<debug> [1588357496.972224] (ttyACM0): <-- '<CR><LF>+CREG: 0<CR><LF><CR><LF>+CGREG: 0<CR><LF><CR><LF>+CEREG: 0<CR><LF>'
<debug> [1588357496.988534] (ttyACM1): <-- '<CR><LF>+CREG: 0<CR><LF><CR><LF>+CGREG: 0<CR><LF><CR><LF>+CEREG: 0<CR><LF>'
<debug> [1588357541.324914] (ttyACM0): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
<debug> [1588357541.340821] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (80)
<debug> [1588357541.347216] (ttyACM1): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
<debug> [1588357541.353503] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (80)
<debug> [1588357542.339139] (ttyACM0): <-- '<CR><LF>+CIEV: 2,2<CR><LF>'
<debug> [1588357542.355230] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40)
<debug> [1588357542.360658] (ttyACM1): <-- '<CR><LF>+CIEV: 2,2<CR><LF>'
<debug> [1588357542.366871] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40)
<debug> [1588357542.867366] (ttyACM0): <-- '<CR><LF>+CREG: 3<CR><LF><CR><LF>+CGREG: 3<CR><LF><CR><LF>+CIEV: 9,0<CR><LF><CR><LF>+CEREG: 3<CR><LF>'
<info> [1588357542.882043] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> unknown)
<debug> [1588357542.887832] (ttyACM1): <-- '<CR><LF>+CREG: 3<CR><LF><CR><LF>+CGREG: 3<CR><LF><CR><LF>+CIEV: 9,0<CR><LF><CR><LF>+CEREG: 3<CR><LF>'
<info> [1588357544.906953] Simple connect started...
<debug> [1588357544.920780] PIN: unspecified
<debug> [1588357544.926464] Operator ID: unspecified
<debug> [1588357544.931458] Allowed roaming: yes
<debug> [1588357544.936117] APN: super
<debug> [1588357544.941953] IP family: ipv4v6
<debug> [1588357544.947786] Allowed authentication: unspecified
<debug> [1588357544.953828] User: unspecified
<debug> [1588357544.958733] Password: unspecified
<debug> [1588357544.964646] Number: *99#
<info> [1588357544.970309] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357544.975522] Simple connect state (5/8): Register
<debug> [1588357544.981692] Launching automatic network registration...
<debug> [1588357544.987701] (ttyACM0) device open count is 2 (open)
<debug> [1588357544.994065] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357587.496311] (ttyACM1): <-- '<CR><LF>+CIEV: 2,3<CR><LF>'
<debug> [1588357587.518512] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<debug> [1588357593.879672] (ttyACM1): <-- '<CR><LF>+CIEV: 9,1<CR><LF><CR><LF>+CEREG: 3<CR><LF>'
<debug> [1588357594.385186] (ttyACM1): <-- '<CR><LF>+CIEV: 2,2<CR><LF>'
<debug> [1588357594.398245] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40)
<debug> [1588357595.412050] (ttyACM1): <-- '<CR><LF>+CIEV: 2,1<CR><LF>'
<debug> [1588357595.426077] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (20)
<debug> [1588357595.746079] (ttyACM1): <-- '<CR><LF>+CIEV: 9,0<CR><LF><CR><LF>+CEREG: 3<CR><LF>'
<debug> [1588357596.796466] (ttyACM0): <-- '<CR><LF>+CME ERROR: 111<CR><LF><CR><LF>+CEREG: 3<CR><LF><CR><LF>+CEREG: 3<CR><LF><CR><LF>+CIEV: 2,3<CR><LF><CR><LF>+CIEV: 9,1<CR><LF><CR><LF>+CIEV: 2,2<CR><LF><CR><LF>+CIEV: 2,1<CR><LF><CR><LF>+CIEV: 9,0<CR><LF>'
<debug> [1588357596.809729] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<debug> [1588357596.816195] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40)
<debug> [1588357596.822481] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (20)
<debug> [1588357596.829824] Got failure code 111: PLMN not allowed
<info> [1588357596.834963] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> idle)
<debug> [1588357596.849310] (ttyACM0) device open count is 1 (close)
<info> [1588357596.861342] Simple connect started...
<debug> [1588357596.866441] PIN: unspecified
<debug> [1588357596.871088] Operator ID: unspecified
<debug> [1588357596.876869] Allowed roaming: yes
<debug> [1588357596.882491] APN: super
<debug> [1588357596.887850] IP family: ipv4
<debug> [1588357596.892729] Allowed authentication: unspecified
<debug> [1588357596.898469] User: unspecified
<debug> [1588357596.903943] Password: unspecified
<debug> [1588357596.908792] Number: *99#
<info> [1588357596.920939] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357596.927029] Simple connect state (5/8): Register
<debug> [1588357596.932799] Launching automatic network registration...
<debug> [1588357596.937827] (ttyACM0) device open count is 2 (open)
<debug> [1588357596.943059] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357596.956277] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357596.962488] Got failure code 100: Unknown error
<debug> [1588357596.968257] (ttyACM0) device open count is 1 (close)
<info> [1588357596.979550] Simple connect started...
<debug> [1588357596.985900] PIN: unspecified
<debug> [1588357596.991987] Operator ID: unspecified
<debug> [1588357596.996897] Allowed roaming: yes
<debug> [1588357597.001762] APN: super
<debug> [1588357597.007817] IP family: ipv6
<debug> [1588357597.013668] Allowed authentication: unspecified
<debug> [1588357597.018401] User: unspecified
<debug> [1588357597.023231] Password: unspecified
<debug> [1588357597.029113] Number: *99#
<info> [1588357597.034910] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357597.039823] Simple connect state (5/8): Register
<debug> [1588357597.044875] Launching automatic network registration...
<debug> [1588357597.051063] (ttyACM0) device open count is 2 (open)
<debug> [1588357597.057295] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357597.065367] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357597.070469] Got failure code 100: Unknown error
<debug> [1588357597.077412] (ttyACM0) device open count is 1 (close)
<info> [1588357597.188031] Simple connect started...
<debug> [1588357597.200781] PIN: unspecified
<debug> [1588357597.205681] Operator ID: unspecified
<debug> [1588357597.211477] Allowed roaming: yes
<debug> [1588357597.217376] APN: super
<debug> [1588357597.222231] IP family: ipv4v6
<debug> [1588357597.227094] Allowed authentication: unspecified
<debug> [1588357597.232916] User: unspecified
<debug> [1588357597.238647] Password: unspecified
<debug> [1588357597.243344] Number: *99#
<info> [1588357597.248042] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357597.254193] Simple connect state (5/8): Register
<debug> [1588357597.260205] Launching automatic network registration...
<debug> [1588357597.265205] (ttyACM0) device open count is 2 (open)
<debug> [1588357597.270486] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357597.279358] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357597.285362] Got failure code 100: Unknown error
<debug> [1588357597.294939] (ttyACM0) device open count is 1 (close)
<info> [1588357597.303064] Simple connect started...
<debug> [1588357597.309198] PIN: unspecified
<debug> [1588357597.315001] Operator ID: unspecified
<debug> [1588357597.319643] Allowed roaming: yes
<debug> [1588357597.324307] APN: super
<debug> [1588357597.330349] IP family: ipv4
<debug> [1588357597.336092] Allowed authentication: unspecified
<debug> [1588357597.340755] User: unspecified
<debug> [1588357597.345642] Password: unspecified
<debug> [1588357597.352149] Number: *99#
<info> [1588357597.357985] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357597.362889] Simple connect state (5/8): Register
<debug> [1588357597.367861] Launching automatic network registration...
<debug> [1588357597.374017] (ttyACM0) device open count is 2 (open)
<debug> [1588357597.380737] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357597.392370] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357597.397599] Got failure code 100: Unknown error
<debug> [1588357597.404769] (ttyACM0) device open count is 1 (close)
<info> [1588357597.418045] Simple connect started...
<debug> [1588357597.423076] PIN: unspecified
<debug> [1588357597.427801] Operator ID: unspecified
<debug> [1588357597.453051] Allowed roaming: yes
<debug> [1588357597.457718] APN: super
<debug> [1588357597.462367] IP family: ipv6
<debug> [1588357597.468454] Allowed authentication: unspecified
<debug> [1588357597.473173] User: unspecified
<debug> [1588357597.477968] Password: unspecified
<debug> [1588357597.483875] Number: *99#
<info> [1588357597.489724] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357597.494780] Simple connect state (5/8): Register
<debug> [1588357597.499873] Launching automatic network registration...
<debug> [1588357597.506260] (ttyACM0) device open count is 2 (open)
<debug> [1588357597.512430] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357597.520239] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357597.525812] Got failure code 100: Unknown error
<debug> [1588357597.532919] (ttyACM0) device open count is 1 (close)
<info> [1588357597.655294] Simple connect started...
<debug> [1588357597.668340] PIN: unspecified
<debug> [1588357597.673217] Operator ID: unspecified
<debug> [1588357597.679472] Allowed roaming: yes
<debug> [1588357597.685248] APN: super
<debug> [1588357597.690016] IP family: ipv4v6
<debug> [1588357597.694779] Allowed authentication: unspecified
<debug> [1588357597.700605] User: unspecified
<debug> [1588357597.707008] Password: unspecified
<debug> [1588357597.711924] Number: *99#
<info> [1588357597.716626] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357597.722885] Simple connect state (5/8): Register
<debug> [1588357597.728794] Launching automatic network registration...
<debug> [1588357597.733897] (ttyACM0) device open count is 2 (open)
<debug> [1588357597.739574] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357597.748995] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357597.755264] Got failure code 100: Unknown error
<debug> [1588357597.761227] (ttyACM0) device open count is 1 (close)
<info> [1588357597.772840] Simple connect started...
<debug> [1588357597.778944] PIN: unspecified
<debug> [1588357597.784756] Operator ID: unspecified
<debug> [1588357597.789514] Allowed roaming: yes
<debug> [1588357597.794289] APN: super
<debug> [1588357597.800079] IP family: ipv4
<debug> [1588357597.805595] Allowed authentication: unspecified
<debug> [1588357597.810523] User: unspecified
<debug> [1588357597.815185] Password: unspecified
<debug> [1588357597.821107] Number: *99#
<info> [1588357597.826762] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357597.833064] Simple connect state (5/8): Register
<debug> [1588357597.838194] Launching automatic network registration...
<debug> [1588357597.845327] (ttyACM0) device open count is 2 (open)
<debug> [1588357597.851483] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357597.860871] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357597.868071] Got failure code 100: Unknown error
<debug> [1588357597.879820] (ttyACM0) device open count is 1 (close)
<info> [1588357597.893501] Simple connect started...
<debug> [1588357597.898974] PIN: unspecified
<debug> [1588357597.903907] Operator ID: unspecified
<debug> [1588357597.910348] Allowed roaming: yes
<debug> [1588357597.916183] APN: super
<debug> [1588357597.920956] IP family: ipv6
<debug> [1588357597.925691] Allowed authentication: unspecified
<debug> [1588357597.931536] User: unspecified
<debug> [1588357597.937729] Password: unspecified
<debug> [1588357597.942637] Number: *99#
<info> [1588357597.947366] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357597.954009] Simple connect state (5/8): Register
<debug> [1588357597.959956] Launching automatic network registration...
<debug> [1588357597.964977] (ttyACM0) device open count is 2 (open)
<debug> [1588357597.970136] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357597.979621] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357597.986230] Got failure code 100: Unknown error
<debug> [1588357597.992661] (ttyACM0) device open count is 1 (close)
<debug> [1588357598.008151] (ttyACM0): <-- '<CR><LF>+UMWI: 0,1<CR><LF><CR><LF>+UMWI: 0,2<CR><LF><CR><LF>+UMWI: 0,3<CR><LF><CR><LF>+UMWI: 0,4<CR><LF>'
<debug> [1588357598.017142] (ttyACM1): <-- '<CR><LF>+UMWI: 0,1<CR><LF><CR><LF>+UMWI: 0,2<CR><LF><CR><LF>+UMWI: 0,3<CR><LF><CR><LF>+UMWI: 0,4<CR><LF>'
<info> [1588357598.102143] Simple connect started...
<debug> [1588357598.114672] PIN: unspecified
<debug> [1588357598.120557] Operator ID: unspecified
<debug> [1588357598.126190] Allowed roaming: yes
<debug> [1588357598.131018] APN: super
<debug> [1588357598.135761] IP family: ipv4v6
<debug> [1588357598.141998] Allowed authentication: unspecified
<debug> [1588357598.147714] User: unspecified
<debug> [1588357598.152514] Password: unspecified
<debug> [1588357598.157429] Number: *99#
<info> [1588357598.163261] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357598.169525] Simple connect state (5/8): Register
<debug> [1588357598.174461] Launching automatic network registration...
<debug> [1588357598.186928] (ttyACM0) device open count is 2 (open)
<debug> [1588357598.193437] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357598.202985] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357598.208456] Got failure code 100: Unknown error
<debug> [1588357598.220257] (ttyACM0) device open count is 1 (close)
<info> [1588357598.227028] Simple connect started...
<debug> [1588357598.232911] PIN: unspecified
<debug> [1588357598.237876] Operator ID: unspecified
<debug> [1588357598.242543] Allowed roaming: yes
<debug> [1588357598.248390] APN: super
<debug> [1588357598.253900] IP family: ipv4
<debug> [1588357598.258867] Allowed authentication: unspecified
<debug> [1588357598.263636] User: unspecified
<debug> [1588357598.269445] Password: unspecified
<debug> [1588357598.275103] Number: *99#
<info> [1588357598.279985] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357598.284938] Simple connect state (5/8): Register
<debug> [1588357598.290992] Launching automatic network registration...
<debug> [1588357598.296824] (ttyACM0) device open count is 2 (open)
<debug> [1588357598.302050] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357598.310109] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357598.316350] Got failure code 100: Unknown error
<debug> [1588357598.322965] (ttyACM0) device open count is 1 (close)
<info> [1588357598.334748] Simple connect started...
<debug> [1588357598.339551] PIN: unspecified
<debug> [1588357598.345418] Operator ID: unspecified
<debug> [1588357598.351172] Allowed roaming: yes
<debug> [1588357598.356043] APN: super
<debug> [1588357598.360788] IP family: ipv6
<debug> [1588357598.366692] Allowed authentication: unspecified
<debug> [1588357598.372336] User: unspecified
<debug> [1588357598.377256] Password: unspecified
<debug> [1588357598.381974] Number: *99#
<info> [1588357598.387851] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357598.395301] Simple connect state (5/8): Register
<debug> [1588357598.401012] Launching automatic network registration...
<debug> [1588357598.406000] (ttyACM0) device open count is 2 (open)
<debug> [1588357598.412310] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357598.421263] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357598.427367] Got failure code 100: Unknown error
<debug> [1588357598.442320] (ttyACM0) device open count is 1 (close)
<debug> [1588357609.086156] (ttyACM0): <-- '<CR><LF>+CREG: 2<CR><LF><CR><LF>+CGREG: 2<CR><LF><CR><LF>+CEREG: 4<CR><LF>'
<info> [1588357609.101944] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> searching)
<info> [1588357609.107552] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> searching)
<debug> [1588357609.120186] (ttyACM1): <-- '<CR><LF>+CREG: 2<CR><LF><CR><LF>+CGREG: 2<CR><LF><CR><LF>+CEREG: 4<CR><LF>'
<debug> [1588357615.644978] (ttyACM0): <-- '<CR><LF>+CIEV: 2,3<CR><LF>'
<debug> [1588357615.658152] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<debug> [1588357615.664826] (ttyACM1): <-- '<CR><LF>+CIEV: 2,3<CR><LF>'
<debug> [1588357615.672132] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<debug> [1588357627.045003] (ttyACM0): <-- '<CR><LF>+CREG: 5,"790E","051E0553",6<CR><LF><CR><LF>+CGREG: 5,"790E","051E0553",6,"6E"<CR><LF><CR><LF>+CIEV: 3,1<CR><LF><CR><LF>+CIEV: 7,1<CR><LF><CR><LF>+CIEV: 9,2<CR><LF><CR><LF>+CEREG: 4<CR><LF>'
<info> [1588357627.060133] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (searching -> registering)
<debug> [1588357627.066070] loading Operator Code...
<debug> [1588357627.071063] (ttyACM0) device open count is 2 (open)
<debug> [1588357627.076199] Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> hspa)
<debug> [1588357627.082483] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '790E', Cell ID: '51E0553')
<debug> [1588357627.088929] (ttyACM1): <-- '<CR><LF>+CREG: 5,"790E","051E0553",6<CR><LF><CR><LF>+CGREG: 5,"790E","051E0553",6,"6E"<CR><LF><CR><LF>+CIEV: 3,1<CR><LF><CR><LF>+CIEV: 7,1<CR><LF><CR><LF>+CIEV: 9,2<CR><LF><CR><LF>+CEREG: 4<CR><LF>'
<debug> [1588357627.099102] (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
<debug> [1588357627.107077] (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"003300310030003400310030",6<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357627.115578] loaded Operator Code: 310410
<debug> [1588357627.121656] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '310', MNC: '410', Location area code: '790E', Cell ID: '51E0553')
<debug> [1588357627.126691] loading Operator Name...
<debug> [1588357627.131440] (ttyACM0) device open count is 3 (open)
<debug> [1588357627.137919] (ttyACM0) device open count is 2 (close)
<debug> [1588357627.147734] (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
<debug> [1588357627.155743] (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"0041005400260054",6<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357627.161240] loaded Operator Name: AT&T
<debug> [1588357627.167922] (ttyACM0) device open count is 1 (close)
<info> [1588357627.175049] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> roaming)
<info> [1588357627.183020] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (searching -> registered)
<debug> [1588357627.188236] Network timezone polling started
<debug> [1588357627.202524] Periodic signal checks enabled
<debug> [1588357627.208748] Periodic signal check refresh requested
<debug> [1588357627.213608] loading signal quality...
<debug> [1588357627.218399] (ttyACM0) device open count is 2 (open)
<debug> [1588357627.225647] (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1588357627.236400] (ttyACM0): <-- '<CR><LF>+CIND: 5,3,1,0,0,0,1,0,2,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357627.242252] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<debug> [1588357627.247353] (ttyACM0) device open count is 1 (close)
<debug> [1588357627.253338] Polling to refresh access technologies is unsupported
<debug> [1588357627.259024] Periodic signal quality checks scheduled in 30s
<debug> [1588357631.870101] (ttyACM0): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
<debug> [1588357631.885459] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (80)
<debug> [1588357631.891577] (ttyACM1): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
<debug> [1588357631.896942] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (80)
<debug> [1588357632.864472] (ttyACM0) device open count is 2 (open)
<debug> [1588357632.880072] (ttyACM0): --> 'AT+CCLK?<CR>'
<debug> [1588357632.890001] (ttyACM0): <-- '<CR><LF>+CCLK: "20/05/01,13:27:08-20"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357632.896172] (ttyACM0) device open count is 1 (close)
<debug> [1588357637.976178] (ttyACM0): <-- '<CR><LF>+CREG: 3<CR><LF><CR><LF>+CGREG: 3<CR><LF><CR><LF>+CIEV: 3,0<CR><LF><CR><LF>+CIEV: 7,0<CR><LF><CR><LF>+CIEV: 9,0<CR><LF><CR><LF>+CEREG: 3<CR><LF><CR><LF>+CEREG: 3<CR><LF>'
<info> [1588357637.990539] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (roaming -> unknown)
<debug> [1588357637.999071] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '790E', Cell ID: '51E0553')
<debug> [1588357638.009036] Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (hspa -> unknown)
<debug> [1588357638.014192] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '0', Cell ID: '0')
<debug> [1588357638.020867] (ttyACM1): <-- '<CR><LF>+CREG: 3<CR><LF><CR><LF>+CGREG: 3<CR><LF><CR><LF>+CIEV: 3,0<CR><LF><CR><LF>+CIEV: 7,0<CR><LF><CR><LF>+CIEV: 9,0<CR><LF><CR><LF>+CEREG: 3<CR><LF><CR><LF>+CEREG: 3<CR><LF>'
<debug> [1588357638.039699] (ttyACM0): <-- '<CR><LF>+CIEV: 2,2<CR><LF>'
<debug> [1588357638.045399] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40)
<debug> [1588357638.050652] (ttyACM1): <-- '<CR><LF>+CIEV: 2,2<CR><LF>'
<debug> [1588357638.057375] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (40)
<debug> [1588357642.862042] (ttyACM0): <-- '<CR><LF>+CEREG: 3<CR><LF>'
<debug> [1588357642.875607] (ttyACM1): <-- '<CR><LF>+CEREG: 3<CR><LF>'
<debug> [1588357643.980414] (ttyACM0): <-- '<CR><LF>+CIEV: 2,1<CR><LF>'
<debug> [1588357643.994360] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (20)
<debug> [1588357643.999736] (ttyACM1): <-- '<CR><LF>+CIEV: 2,1<CR><LF>'
<debug> [1588357644.005333] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (20)
<debug> [1588357653.645803] (ttyACM0): <-- '<CR><LF>+CEREG: 3<CR><LF>'
<debug> [1588357653.660298] (ttyACM1): <-- '<CR><LF>+CEREG: 3<CR><LF>'
<debug> [1588357657.868745] loading signal quality...
<debug> [1588357657.882199] (ttyACM0) device open count is 2 (open)
<debug> [1588357657.887415] (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1588357657.897564] (ttyACM0): <-- '<CR><LF>+CIND: 5,1,0,0,0,0,0,0,0,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357657.904650] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (20)
<debug> [1588357657.909572] Periodic signal quality checks scheduled in 30s
<debug> [1588357657.914470] (ttyACM0) device open count is 1 (close)
<info> [1588357658.910486] Simple connect started...
<debug> [1588357658.924032] PIN: unspecified
<debug> [1588357658.928750] Operator ID: unspecified
<debug> [1588357658.933309] Allowed roaming: yes
<debug> [1588357658.940292] APN: super
<debug> [1588357658.946117] IP family: ipv4v6
<debug> [1588357658.950754] Allowed authentication: unspecified
<debug> [1588357658.955438] User: unspecified
<debug> [1588357658.961367] Password: unspecified
<debug> [1588357658.967144] Number: *99#
<info> [1588357658.971812] Simple connect state (4/8): Wait to get fully enabled
<info> [1588357658.976861] Simple connect state (5/8): Register
<debug> [1588357658.983126] Launching automatic network registration...
<debug> [1588357658.989056] (ttyACM0) device open count is 2 (open)
<debug> [1588357658.994338] (ttyACM0): --> 'AT+COPS=0<CR>'
<debug> [1588357669.409690] (ttyACM1): <-- '<CR><LF>+CEREG: 3<CR><LF>'
<debug> [1588357687.873420] loading signal quality...
<debug> [1588357687.888032] (ttyACM0) device open count is 3 (open)
<debug> [1588357688.935556] (ttyACM1): <-- '<CR><LF>+CIEV: 2,3<CR><LF>'
<debug> [1588357688.952751] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<debug> [1588357693.394295] (ttyACM0): <-- '<CR><LF>OK<CR><LF><CR><LF>+CEREG: 3<CR><LF><CR><LF>+CIEV: 2,3<CR><LF><CR><LF>+CREG: 5,"790E","051E0553",6<CR><LF><CR><LF>+CIEV: 3,1<CR><LF><CR><LF>+CIEV: 7,1<CR><LF><CR><LF>+CIEV: 9,1<CR><LF>'
<debug> [1588357693.409806] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<info> [1588357693.434967] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
<debug> [1588357693.442322] loading Operator Code...
<debug> [1588357693.447249] (ttyACM0) device open count is 4 (open)
<debug> [1588357693.452631] Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> hspa)
<debug> [1588357693.459208] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '790E', Cell ID: '51E0553')
<debug> [1588357693.464855] Running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes')
<debug> [1588357693.471045] (ttyACM0) device open count is 5 (open)
<debug> [1588357693.476882] (ttyACM0) device open count is 4 (close)
<debug> [1588357693.482094] (ttyACM1): <-- '<CR><LF>+CREG: 5,"790E","051E0553",6<CR><LF><CR><LF>+CIEV: 3,1<CR><LF><CR><LF>+CIEV: 7,1<CR><LF><CR><LF>+CIEV: 9,1<CR><LF>'
<debug> [1588357693.492152] (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1588357693.503744] (ttyACM0): <-- '<CR><LF>+CIND: 5,3,1,0,0,0,1,0,1,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357693.511725] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<debug> [1588357693.516791] Periodic signal quality checks scheduled in 30s
<debug> [1588357693.522294] (ttyACM0) device open count is 3 (close)
<debug> [1588357693.528731] (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
<debug> [1588357693.540346] (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"003300310030003400310030",2<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357693.546144] loaded Operator Code: 310410
<debug> [1588357693.551477] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '310', MNC: '410', Location area code: '790E', Cell ID: '51E0553')
<debug> [1588357693.557624] loading Operator Name...
<debug> [1588357693.563381] (ttyACM0) device open count is 4 (open)
<debug> [1588357693.568836] (ttyACM0) device open count is 3 (close)
<debug> [1588357693.576441] (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1588357693.589425] (ttyACM0): <-- '<CR><LF>+CREG: 2,5,"790E","051E0553",6<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357693.597771] (ttyACM0) device open count is 4 (open)
<debug> [1588357693.605923] (ttyACM0) device open count is 3 (close)
<debug> [1588357693.611293] (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
<debug> [1588357693.624315] (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"0041005400260054",2<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357693.631314] loaded Operator Name: AT&T
<debug> [1588357693.636994] (ttyACM0) device open count is 2 (close)
<info> [1588357693.643553] Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> roaming)
<debug> [1588357693.657421] (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1588357693.666819] (ttyACM0): <-- '<CR><LF>+CGREG: 2,3<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357693.672716] (ttyACM0) device open count is 3 (open)
<debug> [1588357693.678498] (ttyACM0) device open count is 2 (close)
<debug> [1588357693.685092] (ttyACM0): --> 'AT+CEREG?<CR>'
<debug> [1588357693.695283] (ttyACM0): <-- '<CR><LF>+CEREG: 2,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357693.702818] Periodic signal check refresh requested
<debug> [1588357693.711255] loading signal quality...
<debug> [1588357693.720776] (ttyACM0) device open count is 3 (open)
<debug> [1588357693.732916] Modem is currently registered in a 3GPP network
<info> [1588357693.741151] Simple connect state (6/8): Bearer
<debug> [1588357693.746116] Creating new bearer...
<debug> [1588357693.752252] u-blox: checking current USB profile...
<debug> [1588357693.758244] (ttyACM0) device open count is 4 (open)
<debug> [1588357693.763376] (ttyACM0) device open count is 3 (close)
<debug> [1588357693.788272] (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1588357693.802202] (ttyACM0): <-- '<CR><LF>+CIND: 5,3,1,0,0,0,1,0,1,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357693.813490] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
<debug> [1588357693.820212] Periodic signal quality checks scheduled in 30s
<debug> [1588357693.828279] (ttyACM0) device open count is 2 (close)
<debug> [1588357693.836427] (ttyACM0): --> 'AT+UUSBCONF?<CR>'
<debug> [1588357693.851158] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357693.861373] Got failure code 100: Unknown error
<debug> [1588357693.871481] u-blox: couldn't load current usb profile: Unknown error
<debug> [1588357693.879703] u-blox: checking current networking mode...
<debug> [1588357693.889145] (ttyACM0) device open count is 3 (open)
<debug> [1588357693.895218] (ttyACM0) device open count is 2 (close)
<debug> [1588357693.903922] (ttyACM0): --> 'AT+UBMCONF?<CR>'
<debug> [1588357693.914706] (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1588357693.920377] Got failure code 100: Unknown error
<debug> [1588357693.925654] u-blox: couldn't load current networking mode: Unknown error
<debug> [1588357693.931975] u-blox: creating generic broadband bearer...
<debug> [1588357693.941272] (ttyACM0) device open count is 3 (open)
<debug> [1588357693.948685] (ttyACM0) device open count is 2 (close)
<debug> [1588357693.956178] u-blox: new generic broadband bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1588357693.972891] Simple connect state (7/8): Connect
<debug> [1588357693.979398] Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1588357693.985256] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
<debug> [1588357693.995764] Launching 3GPP connection attempt with APN 'super'
<debug> [1588357694.009122] Looking for best CID...
<debug> [1588357694.020054] (ttyACM0) device open count is 3 (open)
<debug> [1588357694.029320] (ttyACM0) device open count is 2 (close)
<debug> [1588357694.057559] (ttyACM0): --> 'AT+CGDCONT?<CR>'
<debug> [1588357694.071389] (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IPV4V6","nxtgenphone","0.0.0.0 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF><CR><LF>+CGDCONT: 2,"IPV4V6","iot.aer.net","0.0.0.0 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF><CR><LF>+CGDCONT: 3,"IPV4V6","super","0.0.0.0 0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0",0,0,0,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357694.078665] Found '3' PDP contexts
<debug> [1588357694.084352] PDP context [cid=1] [type='ipv4v6'] [apn='nxtgenphone']
<debug> [1588357694.090165] PDP context [cid=2] [type='ipv4v6'] [apn='iot.aer.net']
<debug> [1588357694.096507] PDP context [cid=3] [type='ipv4v6'] [apn='super']
<debug> [1588357694.106149] Found PDP context with CID 3 and PDP type ipv4v6 for APN 'super'
<debug> [1588357694.116124] (ttyACM0) device open count is 3 (open)
<debug> [1588357694.121012] Connection through a plain serial AT port (ttyACM0)
<debug> [1588357694.127026] (ttyACM0) device open count is 4 (open)
<debug> [1588357694.133013] (ttyACM0) device open count is 3 (close)
<debug> [1588357694.138049] (ttyACM0): --> 'ATD*99***3#<CR>'
<debug> [1588357694.159369] (ttyACM0): <-- '<CR><LF>CONNECT<CR><LF>'
<debug> [1588357694.165889] [ttyACM0] Setting flow control: rts-cts
<debug> [1588357694.171977] (ttyACM0): enabling RTS/CTS flow control
<debug> [1588357694.177409] (ttyACM0): port now connected
<debug> [1588357694.182470] Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1588357694.189712] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
<info> [1588357694.204364] Simple connect state (8/8): All done
<debug> [1588357694.215556] (ttyACM0) device open count is 2 (close)
<debug> [1588357694.472869] (net/ppp0): adding device at sysfs path: /sys/devices/virtual/net/ppp0
<debug> [1588357694.487771] [filter] (net/ppp0) port filtered: virtual device
<debug> [1588357697.443136] (ttyACM1): <-- '<CR><LF>+CGREG: 5,"790E","051E0553",6,"6E"<CR><LF><CR><LF>+CIEV: 9,2<CR><LF><CR><LF>+CEREG: 4<CR><LF>'
<debug> [1588357723.878893] loading signal quality...
<debug> [1588357723.892947] (ttyACM1) device open count is 2 (open)
<debug> [1588357723.898172] (ttyACM1): --> 'AT+CIND?<CR>'
<debug> [1588357723.908780] (ttyACM1): <-- '<CR><LF>+CIND: 5,3,1,0,0,0,1,0,2,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357723.915754] (ttyACM1) Could not parse CIND signal quality results: Could not parse the +CIND response '+UMWI: 0,1
+UMWI: 0,2
+UMWI: 0,3
+UMWI: 0,4
+CIND: 5,3,1,0,0,0,1,0,2,0,0,0': no CIND tag found
<debug> [1588357723.921715] (ttyACM1) device open count is 3 (open)
<debug> [1588357723.926769] (ttyACM1) device open count is 2 (close)
<debug> [1588357723.935254] (ttyACM1): --> 'AT+CSQ<CR>'
<debug> [1588357723.943240] (ttyACM1): <-- '<CR><LF>+CSQ: 22,1<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357723.948901] Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (70)
<debug> [1588357723.955248] Periodic signal quality checks scheduled in 30s
<debug> [1588357723.961197] (ttyACM1) device open count is 1 (close)
<debug> [1588357724.865690] (ttyACM1) device open count is 2 (open)
<debug> [1588357724.882772] (ttyACM1): --> 'AT+CGACT?<CR>'
<debug> [1588357724.894167] (ttyACM1): <-- '<CR><LF>+CGACT: 1,0<CR><LF><CR><LF>+CGACT: 2,0<CR><LF><CR><LF>+CGACT: 3,1<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1588357724.900739] connection status loaded: connected
<debug> [1588357724.905798] (ttyACM1) device open count is 1 (close)
<debug> [1588357729.868285] (ttyACM1) device open count is 2 (open)
<debug> [1588357729.881386] (ttyACM1): --> 'AT+CGACT?<CR>'
<debug> [1588357729.967755] (ttyACM1) unexpected port hangup!
<debug> [1588357729.976456] (ttyACM1) forced to close port
<debug> [1588357729.981632] (ttyACM1) device open count is 0 (close)
<debug> [1588357729.988668] (ttyACM1) closing serial port...
<debug> [1588357730.008063] (ttyACM1) serial port closed
<warn> [1588357730.018495] checking if connected failed: Couldn't check current list of active PDP contexts: Serial port is now closed
<info> [1588357730.024490] (tty/ttyACM0): released by device '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1'
<info> [1588357730.034720] (tty/ttyACM1): released by device '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1'
<info> [1588357730.050015] (tty/ttyACM2): released by device '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1'
<info> [1588357730.076956] (tty/ttyACM3): released by device '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1'
<info> [1588357730.093692] (tty/ttyACM4): released by device '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1'
<info> [1588357730.108045] (tty/ttyACM5): released by device '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1'
<debug> [1588357730.115839] Removing empty device '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1'
<debug> [1588357730.125628] Removing from DBus bearer at '/org/freedesktop/ModemManager1/Bearer/0'
<debug> [1588357730.133782] [device /sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1] unexported modem from path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [1588357730.139238] Periodic signal checks disabled
<warn> [1588357730.149104] (ttyACM0): could not re-acquire serial port lock: (5) Input/output error
<debug> [1588357730.155984] (ttyACM0): port now disconnected
<debug> [1588357730.162391] (ttyACM2) forced to close port
<debug> [1588357730.169222] (ttyACM0) device open count is 1 (close)
<debug> [1588357730.175871] (ttyACM0) forced to close port
<debug> [1588357730.181836] (ttyACM0) device open count is 0 (close)
<debug> [1588357730.188785] (ttyACM0) closing serial port...
<debug> [1588357730.197291] (ttyACM0) serial port closed
<debug> [1588357730.204514] Modem (u-blox) '/sys/devices/platform/soc/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1' completely disposed
<info> [1588357731.601227] Caught signal, shutting down...
<debug> [1588357731.615674] Stopping connection in object manager server
<info> [1588357731.633252] ModemManager is shut down
<info> [1588357731.893688] ModemManager (version 1.8.0) starting in system bus...
<debug> [1588357732.012013] Bus acquired, creating manager...
<debug> [1588357732.040837] [filter] created
<debug> [1588357732.047844] [filter] explicit whitelist: yes
<debug> [1588357732.054196] [filter] virtual devices forbidden: yes
<debug> [1588357732.060214] [filter] net devices allowed: yes
<debug> [1588357732.065240] [filter] cdc-wdm devices allowed: yes
<debug> [1588357732.070268] [filter] tty devices:
<debug> [1588357732.076523] [filter] blacklist applied: yes
<debug> [1588357732.084268] [filter] manual scan only applied: yes
<debug> [1588357732.090210] [filter] platform driver check: yes
<debug> [1588357732.106000] [filter] driver check: no
<debug> [1588357732.114413] [filter] cdc-acm interface check: no
<debug> [1588357732.120083] [filter] with net check: no
<debug> [1588357732.125219] [filter] default: allowed
<debug> [1588357732.132083] [plugin manager] looking for plugins in '/usr/lib/ModemManager'
<debug> [1588357732.154986] [plugin manager] loaded plugin 'Sierra (legacy)'
<debug> [1588357732.168469] [plugin manager] loaded plugin 'Via CBP7'
<debug> [1588357732.186643] [plugin manager] loaded plugin 'Pantech'
<debug> [1588357732.197397] [plugin manager] loaded plugin 'Wavecom'
<debug> [1588357732.214828] [plugin manager] loaded plugin 'u-blox'
<debug> [1588357732.227597] [plugin manager] loaded plugin 'SimTech'
<debug> [1588357732.244471] [plugin manager] loaded plugin 'X22X'
<debug> [1588357732.256333] [plugin manager] loaded plugin 'Ericsson MBM'
<debug> [1588357732.267653] [plugin manager] loaded plugin 'Nokia'
<debug> [1588357732.285001] [plugin manager] loaded plugin 'Thuraya'
<debug> [1588357732.316431] [plugin manager] loaded plugin 'Novatel'
<debug> [1588357732.348939] [plugin manager] loaded plugin 'Option High-Speed'
<debug> [1588357732.375113] [plugin manager] loaded plugin 'Novatel LTE'
<debug> [1588357732.408539] [plugin manager] loaded plugin 'Dell'
<debug> [1588357732.423700] [plugin manager] loaded plugin 'Linktop'
<debug> [1588357732.439954] [plugin manager] loaded plugin 'Nokia (Icera)'
<debug> [1588357732.452403] [plugin manager] loaded plugin 'Cinterion'
<debug> [1588357732.464923] [plugin manager] loaded plugin 'Samsung'
<debug> [1588357732.476006] [plugin manager] loaded plugin 'Generic'
<debug> [1588357732.493817] [plugin manager] loaded plugin 'Sierra'
<debug> [1588357732.507300] [plugin manager] loaded plugin 'Longcheer'
<debug> [1588357732.535870] [plugin manager] loaded plugin 'Telit'
<debug> [1588357732.552871] [plugin manager] loaded plugin 'Huawei'
<debug> [1588357732.565776] [plugin manager] loaded plugin 'AnyDATA'
<debug> [1588357732.575841] [plugin manager] loaded plugin 'Motorola'
<debug> [1588357732.589755] [plugin manager] loaded plugin 'Option'
<debug> [1588357732.602371] [plugin manager] loaded plugin 'ZTE'
<debug> [1588357732.615483] [plugin manager] loaded plugin 'Altair LTE'
<debug> [1588357732.625321] [plugin manager] loaded plugin 'Iridium'
<debug> [1588357732.636530] [plugin manager] loaded plugin 'MTK'
<debug> [1588357732.645341] [plugin manager] loaded plugin 'Haier'
<debug> [1588357732.660623] [plugin manager] loaded plugin 'Quectel'
<debug> [1588357732.667170] [plugin manager] successfully loaded 32 plugins
<warn> [1588357732.682190] Could not acquire the 'org.freedesktop.ModemManager1' service name
<debug> [1588357732.689338] Stopping connection in object manager server
<info> [1588357732.732295] ModemManager is shut down
More information about the ModemManager-devel
mailing list