Hi, I’ve been trying to get ModemManager to work. Connection confirmed to work with Windows, and even on Linux it seems to open the connection successfully, but just failing after it, with ”ModemManager[7787]: <debug> [1373143892.674143] [mm-bearer.c:461] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'No valid data port found to launch connection'” I got a debug log out (attached) by running “ModemManager --debug”, and then trying to establish a connection with NetworkManager. I don’t see anything specific buut this aforementioned error, and looking at the code didn’t give clear hint what could be wrong. Any ideas? -- Terveisin, Samu Voutilainen
ModemManager[7787]: <info> [1373143891.036671] [mm-iface-modem-simple.c:641] connect_auth_ready(): Simple
connect started...
ModemManager[7787]: <debug> [1373143891.036763] [mm-iface-modem-simple.c:651] connect_auth_ready(): PIN:
unspecified
ModemManager[7787]: <debug> [1373143891.036819] [mm-iface-modem-simple.c:653] connect_auth_ready():
Operator ID: unspecified
ModemManager[7787]: <debug> [1373143891.036863] [mm-iface-modem-simple.c:655] connect_auth_ready():
Allowed roaming: yes
ModemManager[7787]: <debug> [1373143891.036933] [mm-iface-modem-simple.c:657] connect_auth_ready(): APN:
internet.saunalahti
ModemManager[7787]: <debug> [1373143891.037006] [mm-iface-modem-simple.c:665] connect_auth_ready(): IP
family: unspecified
ModemManager[7787]: <debug> [1373143891.037054] [mm-iface-modem-simple.c:673] connect_auth_ready():
Allowed authentication: unspecified
ModemManager[7787]: <debug> [1373143891.037101] [mm-iface-modem-simple.c:675] connect_auth_ready(): User:
unspecified
ModemManager[7787]: <debug> [1373143891.037147] [mm-iface-modem-simple.c:677] connect_auth_ready():
Password: unspecified
ModemManager[7787]: <debug> [1373143891.037193] [mm-iface-modem-simple.c:679] connect_auth_ready():
Number: *99#
ModemManager[7787]: <info> [1373143891.037247] [mm-iface-modem-simple.c:461] connection_step(): Simple
connect state (3/8): Enable
ModemManager[7787]: <info> [1373143891.037362] [mm-iface-modem.c:1203]
__iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled
-> enabling)
ModemManager[7787]: <debug> [1373143891.038577] [mm-serial-port.c:891] mm_serial_port_open(): (ttyACM0)
opening serial port...
ModemManager[7787]: <debug> [1373143891.039112] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 1 (open)
ModemManager[7787]: <debug> [1373143891.039203] [mm-serial-port.c:891] mm_serial_port_open(): (ttyACM1)
opening serial port...
ModemManager[7787]: <debug> [1373143891.039577] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1)
device open count is 1 (open)
ModemManager[7787]: <debug> [1373143891.039639] [mm-broadband-modem.c:7912] enabling_started(): Flashing
primary AT port before enabling...
ModemManager[7787]: <debug> [1373143891.039724] [mm-broadband-modem.c:7801] enabling_flash_done(): Running
modem initialization sequence...
ModemManager[7787]: <debug> [1373143891.039826] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.039934] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT&F<CR>'
ModemManager[7787]: <debug> [1373143891.049049] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- 'A'
ModemManager[7787]: <debug> [1373143891.049911] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'T&F<CR>'
ModemManager[7787]: <debug> [1373143891.052571] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.052716] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT*ENAP=0<CR>'
ModemManager[7787]: <debug> [1373143891.061995] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'AT*ENAP=0'
ModemManager[7787]: <debug> [1373143891.062456] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR>'
ModemManager[7787]: <debug> [1373143891.091408] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <--
'<CR><LF>*EMRDY: 1<CR><LF>'
ModemManager[7787]: <debug> [1373143891.093693] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>ERROR<CR><LF>'
ModemManager[7787]: <debug> [1373143891.093815] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got
failure code 100: Unknown error
ModemManager[7787]: <debug> [1373143891.093918] [mm-broadband-modem.c:7783] enabling_modem_init_ready():
Giving some time to settle the modem...
ModemManager[7787]: <debug> [1373143891.094002] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.094090] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>*EMRDY: 1<CR><LF>'
ModemManager[7787]: <debug> [1373143891.594751] [mm-at-serial-port.c:445]
mm_at_serial_port_run_init_sequence(): (ttyACM0): running init sequence...
ModemManager[7787]: <debug> [1373143891.594879] [mm-at-serial-port.c:445]
mm_at_serial_port_run_init_sequence(): (ttyACM1): running init sequence...
ModemManager[7787]: <debug> [1373143891.595018] [mm-iface-modem.c:3105] mm_iface_modem_set_power_state(): No
need to change power state: already in 'on' power state
ModemManager[7787]: <debug> [1373143891.595142] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.595312] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143891.595437] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'ATE0<CR>'
ModemManager[7787]: <debug> [1373143891.595524] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): -->
'ATE0<CR>'
ModemManager[7787]: <debug> [1373143891.599619] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- 'ATE0'
ModemManager[7787]: <debug> [1373143891.599815] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- 'ATE0'
ModemManager[7787]: <debug> [1373143891.600069] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR>'
ModemManager[7787]: <debug> [1373143891.600401] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR>'
ModemManager[7787]: <debug> [1373143891.601590] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.601993] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.602116] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'ATV1<CR>'
ModemManager[7787]: <debug> [1373143891.608014] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.608153] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CMEE=1<CR>'
ModemManager[7787]: <debug> [1373143891.619949] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.620147] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'ATX4<CR>'
ModemManager[7787]: <debug> [1373143891.625913] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.626105] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT&C1<CR>'
ModemManager[7787]: <debug> [1373143891.633014] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.633153] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+IFC=1,1<CR>'
ModemManager[7787]: <debug> [1373143891.645702] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>ERROR<CR><LF>'
ModemManager[7787]: <debug> [1373143891.645855] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got
failure code 100: Unknown error
ModemManager[7787]: <debug> [1373143891.645963] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143891.646130] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CSCS=?<CR>'
ModemManager[7787]: <debug> [1373143891.657332] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CSCS: ('
ModemManager[7787]: <debug> [1373143891.658531] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'"GSM","IRA","8859-1","UTF-8","UCS2")<CR><LF>'
ModemManager[7787]: <debug> [1373143891.658939] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.659170] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143891.659241] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143891.659286] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CSCS="UTF-8"<CR>'
ModemManager[7787]: <debug> [1373143891.677050] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.677176] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143891.677254] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143891.677339] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CSCS?<CR>'
ModemManager[7787]: <debug> [1373143891.687229] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CSCS: "'
ModemManager[7787]: <debug> [1373143891.687444] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'UTF-8"<CR><LF>'
ModemManager[7787]: <debug> [1373143891.687925] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.688016] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.688057] [mm-broadband-modem.c:8456] enabling_step(): Modem has 3GPP
capabilities, enabling the Modem 3GPP interface...
ModemManager[7787]: <debug> [1373143891.688100] [mm-broadband-modem.c:2544]
modem_3gpp_setup_unsolicited_events(): Checking indicator support...
ModemManager[7787]: <debug> [1373143891.688133] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.688183] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CIND=?<CR>'
ModemManager[7787]: <debug> [1373143891.700802] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CIND:
("battchg",(0-5)),("signal",(0-5)),("batterywarning",(0-1)),("chargerconnected",(0-1)),("service",(0-1)),("sounder",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("callsetup",(0-3)),("callheld",(0-1))<CR><LF>'
ModemManager[7787]: <debug> [1373143891.701350] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.701688] [mm-broadband-modem.c:2497] cind_format_check_ready(): Modem
supports signal quality indications via CIND at index '2'(min: 0, max: 5)
ModemManager[7787]: <debug> [1373143891.701744] [mm-broadband-modem.c:2506] cind_format_check_ready(): Modem
supports roaming indications via CIND at index '9'
ModemManager[7787]: <debug> [1373143891.701775] [mm-broadband-modem.c:2515] cind_format_check_ready(): Modem
supports service indications via CIND at index '5'
ModemManager[7787]: <debug> [1373143891.701850] [mm-broadband-modem.c:2448]
set_unsolicited_events_handlers(): (ttyACM0) Setting 3GPP unsolicited events handlers
ModemManager[7787]: <debug> [1373143891.701896] [mm-broadband-modem.c:2448]
set_unsolicited_events_handlers(): (ttyACM1) Setting 3GPP unsolicited events handlers
ModemManager[7787]: <debug> [1373143891.702012] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143891.702086] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143891.702151] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CMER=3,0,0,1<CR>'
ModemManager[7787]: <debug> [1373143891.721160] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.721355] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.721443] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.721518] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): -->
'AT+CMER=3,0,0,1<CR>'
ModemManager[7787]: <debug> [1373143891.740372] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.740553] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.740648] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.740723] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT*ERINFO=1<CR>'
ModemManager[7787]: <debug> [1373143891.757413] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.757564] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT*E2NAP=1<CR>'
ModemManager[7787]: <debug> [1373143891.772254] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.772590] [mm-broadband-modem.c:3508]
modem_3gpp_setup_unsolicited_registration_events(): (ttyACM0) setting up 3GPP unsolicited registration
messages handlers
ModemManager[7787]: <debug> [1373143891.772661] [mm-broadband-modem.c:3508]
modem_3gpp_setup_unsolicited_registration_events(): (ttyACM1) setting up 3GPP unsolicited registration
messages handlers
ModemManager[7787]: <debug> [1373143891.772766] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.772890] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.772975] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CREG=2<CR>'
ModemManager[7787]: <debug> [1373143891.785107] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.785289] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.785376] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.785445] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): -->
'AT+CREG=2<CR>'
ModemManager[7787]: <debug> [1373143891.797378] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.797537] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.797634] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.797733] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CGREG=2<CR>'
ModemManager[7787]: <debug> [1373143891.810512] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.810738] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.810868] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.810979] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): -->
'AT+CGREG=2<CR>'
ModemManager[7787]: <debug> [1373143891.823322] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.823465] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143891.823565] [mm-iface-modem-3gpp.c:745]
mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[7787]: <debug> [1373143891.823650] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143891.823781] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CREG?<CR>'
ModemManager[7787]: <debug> [1373143891.835247] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CREG: 2,5,"71C5","00292906",2<CR><LF>'
ModemManager[7787]: <info> [1373143891.835496] [mm-iface-modem-3gpp.c:1090] update_registration_state():
Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
ModemManager[7787]: <debug> [1373143891.835607] [mm-broadband-modem.c:3380] modem_3gpp_load_operator_code():
loading Operator Code...
ModemManager[7787]: <debug> [1373143891.835718] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <info> [1373143891.835930] [mm-iface-modem.c:726]
mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology
changed (unknown -> umts)
ModemManager[7787]: <debug> [1373143891.836154] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.836363] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 4 (open)
ModemManager[7787]: <debug> [1373143891.836497] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 3 (close)
ModemManager[7787]: <debug> [1373143891.836878] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+COPS=3,2;+COPS?<CR>'
ModemManager[7787]: <debug> [1373143891.859882] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+COPS: 0,2,"24405",2 <CR><LF>'
ModemManager[7787]: <debug> [1373143891.860463] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.860617] [mm-broadband-modem.c:3370]
modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24405
ModemManager[7787]: <debug> [1373143891.860742] [mm-broadband-modem.c:3416] modem_3gpp_load_operator_name():
loading Operator Name...
ModemManager[7787]: <debug> [1373143891.860796] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 4 (open)
ModemManager[7787]: <debug> [1373143891.860887] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 3 (close)
ModemManager[7787]: <debug> [1373143891.861236] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CGREG?<CR>'
ModemManager[7787]: <debug> [1373143891.872539] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CGREG: 2,5,"71C5","00292906",2<CR><LF>'
ModemManager[7787]: <debug> [1373143891.872983] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143891.873107] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143891.873265] [mm-broadband-modem.c:8469] enabling_step(): Modem has
3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
ModemManager[7787]: <debug> [1373143891.873360] [mm-broadband-modem.c:4822]
set_unsolicited_result_code_handlers(): (ttyACM0) Setting unsolicited result code handlers
ModemManager[7787]: <debug> [1373143891.873408] [mm-broadband-modem.c:4822]
set_unsolicited_result_code_handlers(): (ttyACM1) Setting unsolicited result code handlers
ModemManager[7787]: <debug> [1373143891.873522] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143891.873641] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+COPS=3,0;+COPS?<CR>'
ModemManager[7787]: <debug> [1373143892.145247] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+COPS: 0,0,"Saunalahti",2 <CR><LF>'
ModemManager[7787]: <debug> [1373143892.147346] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.147592] [mm-broadband-modem.c:3406]
modem_3gpp_load_operator_name_finish(): loaded Operator Name: Saunalahti
ModemManager[7787]: <debug> [1373143892.147789] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <info> [1373143892.148125] [mm-iface-modem-3gpp.c:1023]
update_registration_reload_current_operator_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP
Registration state changed (registering -> roaming)
ModemManager[7787]: <debug> [1373143892.148408] [mm-iface-modem.c:1372] get_updated_consolidated_state():
Will start keeping track of state for subsystem '3gpp'
ModemManager[7787]: <debug> [1373143892.148783] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CUSD=1<CR>'
ModemManager[7787]: <debug> [1373143892.161037] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.161267] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143892.161496] [mm-broadband-modem.c:8497] enabling_step(): Modem has
location capabilities, enabling the Location interface...
ModemManager[7787]: <debug> [1373143892.161569] [mm-iface-modem-location.c:739] setup_gathering(): Need to
enable the following location sources: '3gpp-lac-ci'
ModemManager[7787]: <debug> [1373143892.161702] [mm-iface-modem-3gpp.c:745]
mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[7787]: <debug> [1373143892.161768] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143892.161845] [mm-broadband-modem.c:3380] modem_3gpp_load_operator_code():
loading Operator Code...
ModemManager[7787]: <debug> [1373143892.161904] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143892.162150] [mm-broadband-modem.c:8510] enabling_step(): Modem has
messaging capabilities, enabling the Messaging interface...
ModemManager[7787]: <debug> [1373143892.162353] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 4 (open)
ModemManager[7787]: <debug> [1373143892.162444] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CREG?<CR>'
ModemManager[7787]: <debug> [1373143892.173565] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CREG: 2,5,"71C5","00292906",2<CR><LF>'
ModemManager[7787]: <info> [1373143892.173833] [mm-iface-modem-location.c:293]
notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0',
MNC: '0', Location area code: '71C5', Cell ID: '292906')
ModemManager[7787]: <debug> [1373143892.174058] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.174272] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 5 (open)
ModemManager[7787]: <debug> [1373143892.174402] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 4 (close)
ModemManager[7787]: <debug> [1373143892.174560] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+COPS=3,2;+COPS?<CR>'
ModemManager[7787]: <debug> [1373143892.198659] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+COPS: 0,2,"24405",2 <CR><LF>'
ModemManager[7787]: <debug> [1373143892.199300] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.199465] [mm-broadband-modem.c:3370]
modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24405
ModemManager[7787]: <info> [1373143892.199628] [mm-iface-modem-location.c:293]
notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC:
'244', MNC: '5', Location area code: '71C5', Cell ID: '292906')
ModemManager[7787]: <debug> [1373143892.199689] [mm-broadband-modem.c:3416] modem_3gpp_load_operator_name():
loading Operator Name...
ModemManager[7787]: <debug> [1373143892.199754] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 5 (open)
ModemManager[7787]: <debug> [1373143892.199862] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 4 (close)
ModemManager[7787]: <debug> [1373143892.199962] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CMGF=?<CR>'
ModemManager[7787]: <debug> [1373143892.211925] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CMGF: (0)<CR><LF>'
ModemManager[7787]: <debug> [1373143892.212519] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.212797] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 5 (open)
ModemManager[7787]: <debug> [1373143892.212913] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 4 (close)
ModemManager[7787]: <debug> [1373143892.213019] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CGREG?<CR>'
ModemManager[7787]: <debug> [1373143892.224323] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CGREG: 2,5,"71C5","00292906",2<CR><LF>'
ModemManager[7787]: <debug> [1373143892.224790] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.224889] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 3 (close)
ModemManager[7787]: <debug> [1373143892.224970] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+COPS=3,0;+COPS?<CR>'
ModemManager[7787]: <debug> [1373143892.496028] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+COPS: 0,0,"Saunalahti",2 <CR><LF>'
ModemManager[7787]: <debug> [1373143892.496692] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.496957] [mm-broadband-modem.c:3406]
modem_3gpp_load_operator_name_finish(): loaded Operator Name: Saunalahti
ModemManager[7787]: <debug> [1373143892.497164] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143892.497336] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CMGF=0<CR>'
ModemManager[7787]: <debug> [1373143892.510006] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.510178] [mm-broadband-modem.c:5399] cmgf_set_ready(): Successfully
set preferred SMS mode: 'PDU'
ModemManager[7787]: <debug> [1373143892.510396] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143892.510509] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143892.510824] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CPMS="","ME","ME"<CR>'
ModemManager[7787]: <debug> [1373143892.534996] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CMS ERROR: 321<CR><LF>'
ModemManager[7787]: <debug> [1373143892.535231] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got
failure code 321: Invalid index
ModemManager[7787]: <debug> [1373143892.535331] [mm-iface-modem-messaging.c:779] set_default_storage_ready():
Couldn't set default storage: 'Invalid index'
ModemManager[7787]: <debug> [1373143892.535394] [mm-broadband-modem.c:6097]
modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
ModemManager[7787]: <debug> [1373143892.535462] [mm-broadband-modem.c:5293]
mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
ModemManager[7787]: <debug> [1373143892.535546] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143892.535632] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143892.535717] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CPMS="ME"<CR>'
ModemManager[7787]: <debug> [1373143892.553433] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CPMS: 0,30,0,50,0,30<CR><LF>'
ModemManager[7787]: <debug> [1373143892.554052] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.554198] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143892.554340] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143892.554428] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CMGL=4<CR>'
ModemManager[7787]: <debug> [1373143892.567580] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.567841] [mm-broadband-modem.c:6097]
modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
ModemManager[7787]: <debug> [1373143892.567925] [mm-broadband-modem.c:5293]
mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
ModemManager[7787]: <debug> [1373143892.568022] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143892.568172] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143892.568311] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CPMS="SM"<CR>'
ModemManager[7787]: <debug> [1373143892.585615] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CPMS: 0,50,0,50,0,30<CR><LF>'
ModemManager[7787]: <debug> [1373143892.586209] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.586410] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143892.586523] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143892.586592] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CMGL=4<CR>'
ModemManager[7787]: <debug> [1373143892.599663] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.599878] [mm-broadband-modem.c:5705]
set_messaging_unsolicited_events_handlers(): (ttyACM0) Setting messaging unsolicited events handlers
ModemManager[7787]: <debug> [1373143892.599947] [mm-broadband-modem.c:5705]
set_messaging_unsolicited_events_handlers(): (ttyACM1) Setting messaging unsolicited events handlers
ModemManager[7787]: <debug> [1373143892.600043] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143892.600153] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143892.600263] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CNMI=2,1,2,1,0<CR>'
ModemManager[7787]: <debug> [1373143892.621179] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.621330] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <info> [1373143892.621427] [mm-iface-modem.c:1203]
__iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling
-> registered)
ModemManager[7787]: <debug> [1373143892.621860] [mm-iface-modem.c:1127]
periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
ModemManager[7787]: <debug> [1373143892.621950] [mm-broadband-modem.c:1963] modem_load_signal_quality():
loading signal quality...
ModemManager[7787]: <debug> [1373143892.622052] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 2 (open)
ModemManager[7787]: <debug> [1373143892.622149] [mm-iface-modem.c:844]
periodic_access_technologies_check_enable(): Periodic access technology checks enabled
ModemManager[7787]: <info> [1373143892.622271] [mm-iface-modem-simple.c:469] connection_step(): Simple
connect state (4/8): Wait to get fully enabled
ModemManager[7787]: <info> [1373143892.622383] [mm-iface-modem-simple.c:478] connection_step(): Simple
connect state (5/8): Register
ModemManager[7787]: <debug> [1373143892.622476] [mm-iface-modem-3gpp.c:384]
mm_iface_modem_3gpp_register_in_network(): Already registered in network '24405', automatic registration not
launched...
ModemManager[7787]: <info> [1373143892.622568] [mm-iface-modem-simple.c:501] connection_step(): Simple
connect state (6/8): Bearer
ModemManager[7787]: <debug> [1373143892.622623] [mm-iface-modem-simple.c:521] connection_step(): Creating new
bearer...
ModemManager[7787]: <debug> [1373143892.622672] [mbm/mm-broadband-modem-mbm.c:128] modem_create_bearer():
Creating MBM bearer...
(ModemManager:7787): GLib-GObject-CRITICAL **: g_param_spec_enum: assertion `G_TYPE_IS_ENUM (enum_type)'
failed
(ModemManager:7787): GLib-GObject-CRITICAL **: g_object_class_install_property: assertion `G_IS_PARAM_SPEC
(pspec)' failed
ModemManager[7787]: <debug> [1373143892.628133] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143892.628225] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <debug> [1373143892.628436] [mbm/mm-broadband-modem-mbm.c:91]
modem_create_bearer_finish(): New MBM bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[7787]: <info> [1373143892.628523] [mm-iface-modem-simple.c:583] connection_step(): Simple
connect state (7/8): Connect
ModemManager[7787]: <debug> [1373143892.628593] [mm-bearer.c:586] mm_bearer_connect(): Connecting bearer
'/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[7787]: <info> [1373143892.628668] [mm-iface-modem.c:1203]
__iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed
(registered -> connecting)
ModemManager[7787]: <debug> [1373143892.629115] [mm-broadband-bearer.c:1230] connect(): Launching 3GPP
connection attempt with APN 'internet.saunalahti'
ModemManager[7787]: <debug> [1373143892.629231] [mm-broadband-bearer.c:177] detailed_connect_context_new():
No specific IP family requested, defaulting to ipv4
ModemManager[7787]: <debug> [1373143892.629290] [mm-broadband-bearer.c:1020] connect_3gpp(): Looking for best
CID...
ModemManager[7787]: <debug> [1373143892.629357] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0)
device open count is 3 (open)
ModemManager[7787]: <debug> [1373143892.629449] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CIND?<CR>'
ModemManager[7787]: <debug> [1373143892.641656] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CIND: 5,5,0,0,1,1,0,0,1,0,0,0<CR><LF>'
ModemManager[7787]: <debug> [1373143892.642279] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.642505] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 2 (close)
ModemManager[7787]: <info> [1373143892.642716] [mm-iface-modem.c:976] update_signal_quality(): Modem
/org/freedesktop/ModemManager1/Modem/0: signal quality updated (100)
ModemManager[7787]: <debug> [1373143892.642773] [mm-iface-modem.c:1046] signal_quality_check_ready():
Periodic signal quality checks rescheduled (interval = 30s)
ModemManager[7787]: <debug> [1373143892.643043] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): -->
'AT+CGDCONT?<CR>'
ModemManager[7787]: <debug> [1373143892.673075] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>+CGDCONT: 1,"IP","internet.saunalahti","0.0.0.0",0,0<CR><LF>'
ModemManager[7787]: <debug> [1373143892.673594] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'<CR><LF>OK<CR><LF>'
ModemManager[7787]: <debug> [1373143892.673820] [mm-broadband-bearer.c:937] parse_pdp_list(): Found '1' PDP
contexts
ModemManager[7787]: <debug> [1373143892.673876] [mm-broadband-bearer.c:946] parse_pdp_list(): PDP context
[cid=1] [type='ipv4'] [apn='internet.saunalahti']
ModemManager[7787]: <debug> [1373143892.673921] [mm-broadband-bearer.c:970] parse_pdp_list(): Found PDP
context with CID 1 and PDP type ipv4 for APN 'internet.saunalahti'
ModemManager[7787]: <debug> [1373143892.674024] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0)
device open count is 1 (close)
ModemManager[7787]: <debug> [1373143892.674143] [mm-bearer.c:461] connect_ready(): Couldn't connect bearer
'/org/freedesktop/ModemManager1/Bearer/0': 'No valid data port found to launch connection'
ModemManager[7787]: <info> [1373143892.674257] [mm-iface-modem.c:1203]
__iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed
(connecting -> registered)
ModemManager[7787]: <debug> [1373143892.674895] [mm-iface-modem-simple.c:221] connect_bearer_ready():
Couldn't connect bearer: 'No valid data port found to launch connection'
Attachment:
""
Description: Binary data
Attachment:
""
Description: Binary data